98_UPNPController/98_DLNAController und VestelTVs

[RockFan]

Hallo Markus,

Testergebnisse mit dem Windows-Media stehen ab Post https://forum.fhem.de/index.php/topic,119546.msg1141769.html#msg1141769 beschrieben. FB-Aktivitäten sieht man im Mediaplayer nicht. Als ich in den Tests gestern vom Smarphone gestreamt hatte war das nicht anders. Ich hatte es sogar mit verschiedenen Apps probiert.

Habe die Änderungen an den Attributen envNamespace u. envPrefix, wie in dem Post https://forum.fhem.de/index.php/topic,118837.msg1137371.html#msg1137371 von Dir beschrieben vorgenommen, ein modify gemacht und dann nochmal mit dem Mediaplayer getestet. Die FB-Aktionen zu Lautstärke und Mute kommen am Mediaplayer nicht an. Jedoch funktioniert das umgekehrt vom Mediaplayer zum TV.

Das Streamen an das TV-Gerät allgemein sehr instabil. Da hatte ich während meiner Tests in den letzten Tagen und auch heute vom Mediaplayer immer wieder Abbrüche wovon die Applikation (z.B. Mediaplayer nichts mitbekommt. Da hilft es nur den Stream zu stoppen und neu zu streamen.

Wireshark ist mir übrigens ein Begriff, hatte das Tool aber nie verwendet.

Der Timeout ist übrigens noch auf 100.

Hier die Logs von heute:

Code Auswählen Erweitern


2021.04.04 12:33:49 4: UPNPController: discovery message of ALDISUED TV with UDN: uuid:13cae598-3cca-165a-8000-0009dfbd33fb action: deviceAdded
2021.04.04 12:33:49 5: UPNPController: UPNP_Controller: alive-notify message device uuid:13cae598-3cca-165a-8000-0009dfbd33fb received
2021.04.04 12:33:49 5: UPNPController: warning: main, Use of uninitialized value in string eq at ./FHEM/98_UPNPController.pm line 547.

2021.04.04 12:33:49 5: UPNPController: warning: main, Use of uninitialized value in string eq at ./FHEM/98_UPNPController.pm line 547.

2021.04.04 12:33:49 5: UPNPController: UPNP_Controller: device uuid:13cae598-3cca-165a-8000-0009dfbd33fb found. Check for processing
2021.04.04 12:33:49 5: UPNPController: UPNP_Controller: device uuid:13cae598-3cca-165a-8000-0009dfbd33fb will be controlled with 192.168.178.61_2870
2021.04.04 12:33:49 5: UPNPController: UPNP_Controller: device uuid:13cae598-3cca-165a-8000-0009dfbd33fb has service with serviceId: RenderingControl
2021.04.04 12:33:49 4: UPNPController: subscribe: reading  192.168.178.61_2870-zs-RenderingControl, uniquedevice 192.168.178.61_2870, service RenderingControl
2021.04.04 12:33:49 5: UPNPController: RenderingControl: urn:schemas-upnp-org:service:RenderingControl:1 found. OK.
2021.04.04 12:33:49 5: UPNPController: RenderingControl: urn:schemas-upnp-org:service:RenderingControl:1 found. OK.
2021.04.04 12:33:50 4: UPNPController: subscribe: reading  192.168.178.61_2870-zs-RenderingControl, uniquedevice 192.168.178.61_2870, service RenderingControl timeout: 300
2021.04.04 12:33:50 5: UPNPController: UPNP_Controller: device uuid:13cae598-3cca-165a-8000-0009dfbd33fb has service with serviceId: ConnectionManager
2021.04.04 12:33:50 5: UPNPController: UPNP_Controller: device uuid:13cae598-3cca-165a-8000-0009dfbd33fb has service with serviceId: AVTransport
2021.04.04 12:33:50 4: UPNPController: subscribe: reading  192.168.178.61_2870-zs-AVTransport, uniquedevice 192.168.178.61_2870, service AVTransport
2021.04.04 12:33:50 5: UPNPController: AVTransport: urn:schemas-upnp-org:service:AVTransport:1 found. OK.
2021.04.04 12:33:50 5: UPNPController: AVTransport: urn:schemas-upnp-org:service:AVTransport:1 found. OK.
2021.04.04 12:33:50 4: UPNPController: subscribe: reading  192.168.178.61_2870-zs-AVTransport, uniquedevice 192.168.178.61_2870, service AVTransport timeout: 300
2021.04.04 12:33:50 5: UPNP_Controller: dispatch AVTransport|192.168.178.61_2870|online|urn:schemas-upnp-org:metadata-1-0/AVT
2021.04.04 12:33:50 5: DLNAController: message from UPNP_Controller, message: AVTransport|192.168.178.61_2870|online|urn:schemas-upnp-org:metadata-1-0/AVT
2021.04.04 12:33:50 4: DLNAController: message parameters: AVTransport, 192.168.178.61_2870, online, urn:schemas-upnp-org:metadata-1-0/AVT
2021.04.04 12:33:50 5: UPNPController: Received event: by 192.168.178.61_2870-zs-RenderingControl UPnP::ControlPoint::Service=HASH(0xa4a1170) $VAR1 = 'LastChange';
$VAR2 = '<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="0"/><VolumeDB channel="Master" val="-32768"/><PresetNameList val="FactoryDefaults"/></InstanceID></Event>';

2021.04.04 12:33:50 5: UPNPController: Received event: by 192.168.178.61_2870-zs-RenderingControl property: <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="0"/><VolumeDB channel="Master" val="-32768"/><PresetNameList val="FactoryDefaults"/></InstanceID></Event>
2021.04.04 12:33:50 5: UPNP_Controller: dispatch RenderingControl|192.168.178.61_2870|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="0"/><VolumeDB channel="Master" val="-32768"/><PresetNameList val="FactoryDefaults"/></InstanceID></Event>
2021.04.04 12:33:50 5: DLNAController: message from UPNP_Controller, message: RenderingControl|192.168.178.61_2870|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="0"/><VolumeDB channel="Master" val="-32768"/><PresetNameList val="FactoryDefaults"/></InstanceID></Event>
2021.04.04 12:33:50 4: DLNAController: message parameters: RenderingControl, 192.168.178.61_2870, LastChange, <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="0"/><VolumeDB channel="Master" val="-32768"/><PresetNameList val="FactoryDefaults"/></InstanceID></Event>
2021.04.04 12:33:50 5: UPNPController: Received event: by 192.168.178.61_2870-zs-AVTransport UPnP::ControlPoint::Service=HASH(0xa373c90) $VAR1 = 'LastChange';
$VAR2 = '&lt;Event xmlns=&quot;urn:schemas-upnp-org:metadata-1-0/AVT/&quot;&gt;&lt;InstanceID val=&quot;0&quot;&gt;&lt;AVTransportURI val=&quot;&quot;/&gt;&lt;NumberOfTracks val=&quot;0&quot;/&gt;&lt;AVTransportURIMetaData val=&quot;&quot;/&gt;&lt;PlaybackStorageMedium val=&quot;NONE&quot;/&gt;&lt;CurrentMediaCategory val=&quot;NO_MEDIA&quot;/&gt;&lt;PossiblePlaybackStorageMedia val=&quot;NONE,NETWORK&quot;/&gt;&lt;RecordStorageMedium val=&quot;NOT_IMPLEMENTED&quot;/&gt;&lt;PossibleRecordStorageMedia val=&quot;NOT_IMPLEMENTED&quot;/&gt;&lt;RecordMediumWriteStatus val=&quot;NOT_IMPLEMENTED&quot;/&gt;&lt;NextAVTransportURI val=&quot;NOT_IMPLEMENTED&quot;/&gt;&lt;NextAVTransportURIMetaData val=&quot;NOT_IMPLEMENTED&quot;/&gt;&lt;CurrentPlayMode val=&quot;NORMAL&quot;/&gt;&lt;CurrentRecordQualityMode val=&quot;NOT_IMPLEMENTED&quot;/&gt;&lt;PossibleRecordQualityModes val=&quot;NOT_IMPLEMENTED&quot;/&gt;&lt;TransportState val=&quot;NO_MEDIA_PRESENT&quot;/&gt;&lt;TransportStatus val=&quot;OK&quot;/&gt;&lt;TransportPlaySpeed val=&quot;1&quot;/&gt;&lt;CurrentTrack val=&quot;0&quot;/&gt;&lt;CurrentTrackDuration val=&quot;00:00:00&quot;/&gt;&lt;CurrentTrackMetaData val=&quot;&quot;/&gt;&lt;CurrentTrackURI val=&quot;&quot;/&gt;&lt;CurrentMediaDuration val=&quot;00:00:00&quot;/&gt;&lt;CurrentTransportActions val=&quot;&quot;/&gt;&lt;/InstanceID&gt;&lt;/Event&gt;';

2021.04.04 12:33:50 5: UPNPController: Received event: by 192.168.178.61_2870-zs-AVTransport property: <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/"><InstanceID val="0"><AVTransportURI val=""/><NumberOfTracks val="0"/><AVTransportURIMetaData val=""/><PlaybackStorageMedium val="NONE"/><CurrentMediaCategory val="NO_MEDIA"/><PossiblePlaybackStorageMedia val="NONE,NETWORK"/><RecordStorageMedium val="NOT_IMPLEMENTED"/><PossibleRecordStorageMedia val="NOT_IMPLEMENTED"/><RecordMediumWriteStatus val="NOT_IMPLEMENTED"/><NextAVTransportURI val="NOT_IMPLEMENTED"/><NextAVTransportURIMetaData val="NOT_IMPLEMENTED"/><CurrentPlayMode val="NORMAL"/><CurrentRecordQualityMode val="NOT_IMPLEMENTED"/><PossibleRecordQualityModes val="NOT_IMPLEMENTED"/><TransportState val="NO_MEDIA_PRESENT"/><TransportStatus val="OK"/><TransportPlaySpeed val="1"/><CurrentTrack val="0"/><CurrentTrackDuration val="00:00:00"/><CurrentTrackMetaData val=""/><CurrentTrackURI val=""/><CurrentMediaDuration val="00:00:00"/><CurrentTransportActions val=""/></InstanceID></Event>
2021.04.04 12:33:50 5: UPNP_Controller: dispatch AVTransport|192.168.178.61_2870|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/"><InstanceID val="0"><AVTransportURI val=""/><NumberOfTracks val="0"/><AVTransportURIMetaData val=""/><PlaybackStorageMedium val="NONE"/><CurrentMediaCategory val="NO_MEDIA"/><PossiblePlaybackStorageMedia val="NONE,NETWORK"/><RecordStorageMedium val="NOT_IMPLEMENTED"/><PossibleRecordStorageMedia val="NOT_IMPLEMENTED"/><RecordMediumWriteStatus val="NOT_IMPLEMENTED"/><NextAVTransportURI val="NOT_IMPLEMENTED"/><NextAVTransportURIMetaData val="NOT_IMPLEMENTED"/><CurrentPlayMode val="NORMAL"/><CurrentRecordQualityMode val="NOT_IMPLEMENTED"/><PossibleRecordQualityModes val="NOT_IMPLEMENTED"/><TransportState val="NO_MEDIA_PRESENT"/><TransportStatus val="OK"/><TransportPlaySpeed val="1"/><CurrentTrack val="0"/><CurrentTrackDuration val="00:00:00"/><CurrentTrackMetaData val=""/><CurrentTrackURI val=""/><CurrentMediaDuration val="00:00:00"/><CurrentTransportActions val=""/></InstanceID></Event>
2021.04.04 12:33:50 5: DLNAController: message from UPNP_Controller, message: AVTransport|192.168.178.61_2870|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/"><InstanceID val="0"><AVTransportURI val=""/><NumberOfTracks val="0"/><AVTransportURIMetaData val=""/><PlaybackStorageMedium val="NONE"/><CurrentMediaCategory val="NO_MEDIA"/><PossiblePlaybackStorageMedia val="NONE,NETWORK"/><RecordStorageMedium val="NOT_IMPLEMENTED"/><PossibleRecordStorageMedia val="NOT_IMPLEMENTED"/><RecordMediumWriteStatus val="NOT_IMPLEMENTED"/><NextAVTransportURI val="NOT_IMPLEMENTED"/><NextAVTransportURIMetaData val="NOT_IMPLEMENTED"/><CurrentPlayMode val="NORMAL"/><CurrentRecordQualityMode val="NOT_IMPLEMENTED"/><PossibleRecordQualityModes val="NOT_IMPLEMENTED"/><TransportState val="NO_MEDIA_PRESENT"/><TransportStatus val="OK"/><TransportPlaySpeed val="1"/><CurrentTrack val="0"/><CurrentTrackDuration val="00:00:00"/><CurrentTrackMetaData val=""/><CurrentTrackURI val=""/><CurrentMediaDuration val="00:00:00"/><CurrentTransportActions val=""/></InstanceID></Event>
2021.04.04 12:33:50 4: DLNAController: message parameters: AVTransport, 192.168.178.61_2870, LastChange, <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/"><InstanceID val="0"><AVTransportURI val=""/><NumberOfTracks val="0"/><AVTransportURIMetaData val=""/><PlaybackStorageMedium val="NONE"/><CurrentMediaCategory val="NO_MEDIA"/><PossiblePlaybackStorageMedia val="NONE,NETWORK"/><RecordStorageMedium val="NOT_IMPLEMENTED"/><PossibleRecordStorageMedia val="NOT_IMPLEMENTED"/><RecordMediumWriteStatus val="NOT_IMPLEMENTED"/><NextAVTransportURI val="NOT_IMPLEMENTED"/><NextAVTransportURIMetaData val="NOT_IMPLEMENTED"/><CurrentPlayMode val="NORMAL"/><CurrentRecordQualityMode val="NOT_IMPLEMENTED"/><PossibleRecordQualityModes val="NOT_IMPLEMENTED"/><TransportState val="NO_MEDIA_PRESENT"/><TransportStatus val="OK"/><TransportPlaySpeed val="1"/><CurrentTrack val="0"/><CurrentTrackDuration val="00:00:00"/><CurrentTrackMetaData val=""/><CurrentTrackURI val=""/><CurrentMediaDuration val="00:00:00"/><CurrentTransportActions val=""/></InstanceID></Event>

2021.04.04 12:34:00 4: UPNPController: try to renew subscriptions for services, device UPNP_Controller with timeout: 100
2021.04.04 12:34:08 4: UPNPController: discovery message of BUWOEssplatz with UDN: uuid:123402409-bccb-40e7-8e6c-3810D5023B0A action: deviceAdded
2021.04.04 12:34:08 5: UPNPController: UPNP_Controller: alive-notify message device uuid:123402409-bccb-40e7-8e6c-3810D5023B0A received
2021.04.04 12:34:08 5: UPNPController: warning: main, Use of uninitialized value in string eq at ./FHEM/98_UPNPController.pm line 547.

2021.04.04 12:34:08 5: UPNPController: UPNP_Controller: device uuid:123402409-bccb-40e7-8e6c-3810D5023B0A found. Check for processing
2021.04.04 12:34:08 5: UPNPController: UPNP_Controller: device uuid:123402409-bccb-40e7-8e6c-3810D5023B0A will be controlled with 192.168.178.42_49000
2021.04.04 12:34:08 5: UPNPController: UPNP_Controller: device uuid:123402409-bccb-40e7-8e6c-3810D5023B0A has service with serviceId: fritzbox

2021.04.04 12:34:11 4: UPNPController: discovery message of Familie (MEDIACENTERPC : Windows Media Player) with UDN: uuid:f69b1dc1-94aa-49d1-be51-fedbc77b0b9c action: deviceAdded
2021.04.04 12:34:11 5: UPNPController: UPNP_Controller: alive-notify message device uuid:f69b1dc1-94aa-49d1-be51-fedbc77b0b9c received
2021.04.04 12:34:11 5: UPNPController: warning: main, Use of uninitialized value in string eq at ./FHEM/98_UPNPController.pm line 547.

2021.04.04 12:34:11 5: UPNPController: warning: main, Use of uninitialized value in string eq at ./FHEM/98_UPNPController.pm line 547.

2021.04.04 12:34:11 5: UPNPController: UPNP_Controller: device uuid:f69b1dc1-94aa-49d1-be51-fedbc77b0b9c found. Check for processing
2021.04.04 12:34:11 5: UPNPController: UPNP_Controller: device uuid:f69b1dc1-94aa-49d1-be51-fedbc77b0b9c will be controlled with 192.168.178.38_2869
2021.04.04 12:34:11 5: UPNPController: UPNP_Controller: device uuid:f69b1dc1-94aa-49d1-be51-fedbc77b0b9c has service with serviceId: RenderingControl
2021.04.04 12:34:11 4: UPNPController: subscribe: reading  192.168.178.38_2869-zs-RenderingControl, uniquedevice 192.168.178.38_2869, service RenderingControl
2021.04.04 12:34:11 5: UPNPController: RenderingControl: urn:schemas-upnp-org:service:RenderingControl:1 found. OK.
2021.04.04 12:34:11 5: UPNPController: RenderingControl: urn:schemas-upnp-org:service:RenderingControl:1 found. OK.
2021.04.04 12:34:12 4: UPNPController: subscribe: reading  192.168.178.38_2869-zs-RenderingControl, uniquedevice 192.168.178.38_2869, service RenderingControl timeout: 300
2021.04.04 12:34:12 5: UPNPController: UPNP_Controller: device uuid:f69b1dc1-94aa-49d1-be51-fedbc77b0b9c has service with serviceId: AVTransport
2021.04.04 12:34:12 5: UPNPController: UPNP_Controller: device uuid:f69b1dc1-94aa-49d1-be51-fedbc77b0b9c has service with serviceId: ConnectionManager
2021.04.04 12:34:12 5: UPNP_Controller: dispatch AVTransport|192.168.178.38_2869|online|urn:schemas-upnp-org:metadata-1-0/AVT
2021.04.04 12:34:12 5: DLNAController: message from UPNP_Controller, message: AVTransport|192.168.178.38_2869|online|urn:schemas-upnp-org:metadata-1-0/AVT
2021.04.04 12:34:12 4: DLNAController: message parameters: AVTransport, 192.168.178.38_2869, online, urn:schemas-upnp-org:metadata-1-0/AVT
2021.04.04 12:34:12 5: UPNPController: Received event: by 192.168.178.38_2869-zs-RenderingControl UPnP::ControlPoint::Service=HASH(0x9ea7e18) $VAR1 = 'LastChange';
$VAR2 = '&lt;Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"&gt;&lt;InstanceID val="0"&gt;&lt;Mute channel="Master" val="0"/&gt;&lt;Volume channel="Master" val="100"/&gt;&lt;PresetNameList val="FactoryDefaults"/&gt;&lt;/InstanceID&gt;&lt;/Event&gt;';

2021.04.04 12:34:12 5: UPNPController: Received event: by 192.168.178.38_2869-zs-RenderingControl property: <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="100"/><PresetNameList val="FactoryDefaults"/></InstanceID></Event>
2021.04.04 12:34:12 5: UPNP_Controller: dispatch RenderingControl|192.168.178.38_2869|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="100"/><PresetNameList val="FactoryDefaults"/></InstanceID></Event>
2021.04.04 12:34:12 5: DLNAController: message from UPNP_Controller, message: RenderingControl|192.168.178.38_2869|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="100"/><PresetNameList val="FactoryDefaults"/></InstanceID></Event>
2021.04.04 12:34:12 4: DLNAController: message parameters: RenderingControl, 192.168.178.38_2869, LastChange, <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="100"/><PresetNameList val="FactoryDefaults"/></InstanceID></Event>

2021.04.04 12:34:17 4: UPNPController: discovery message of FRITZ!Box 7590 (UI) with UDN: uuid:123402409-bccb-40e7-8e6c-444E6D5B9ECD action: deviceAdded
2021.04.04 12:34:17 5: UPNPController: UPNP_Controller: alive-notify message device uuid:123402409-bccb-40e7-8e6c-444E6D5B9ECD received
2021.04.04 12:34:17 5: UPNPController: warning: main, Use of uninitialized value in string eq at ./FHEM/98_UPNPController.pm line 547.

2021.04.04 12:34:17 5: UPNPController: UPNP_Controller: device uuid:123402409-bccb-40e7-8e6c-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:17 5: UPNPController: UPNP_Controller: device uuid:123402409-bccb-40e7-8e6c-444E6D5B9ECD will be controlled with 192.168.178.1_49000
2021.04.04 12:34:17 5: UPNPController: UPNP_Controller: device uuid:123402409-bccb-40e7-8e6c-444E6D5B9ECD has service with serviceId: fritzbox

2021.04.04 12:34:22 4: UPNPController: try to renew subscriptions for services, device UPNP_Controller with timeout: 100
2021.04.04 12:34:25 4: UPNPController: discovery message of InternetGatewayDeviceV2 - FRITZ!Box 7590 (UI) with UDN: uuid:75802409-bccb-40e7-9f6c-444E6D5B9ECD action: deviceAdded
2021.04.04 12:34:25 5: UPNPController: UPNP_Controller: alive-notify message device uuid:75802409-bccb-40e7-9f6c-444E6D5B9ECD received
2021.04.04 12:34:25 5: UPNPController: warning: main, Use of uninitialized value in string eq at ./FHEM/98_UPNPController.pm line 547.

2021.04.04 12:34:25 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6c-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:25 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6c-444E6D5B9ECD will be controlled with 192.168.178.1_49000_1
2021.04.04 12:34:25 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6c-444E6D5B9ECD has service with serviceId: any1
2021.04.04 12:34:25 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_1-zz is child with Id: uuid:75802409-bccb-40e7-9f6b-444E6D5B9ECD
2021.04.04 12:34:25 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6b-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6b-444E6D5B9ECD will be controlled with 192.168.178.1_49000_1-zz
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6b-444E6D5B9ECD is not a parent
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6b-444E6D5B9ECD has service with serviceId: WANCommonIFC1
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_1-zz-zz has parent UPnP::ControlPoint::Device=HASH(0xa76cbc8)
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_1-zz-zz is child with Id: uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD will be controlled with 192.168.178.1_49000_1-zz-zz
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD is not a parent
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD has service with serviceId: WANDSLLinkC1
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD has service with serviceId: WANIPConn1
2021.04.04 12:34:26 4: UPNPController: subscribe: reading  192.168.178.1_49000_1-zz-zz-zs-WANIPConn1, uniquedevice 192.168.178.1_49000_1-zz-zz, service WANIPConn1
2021.04.04 12:34:26 4: UPNPController: WANIPConn1 unknown for UPNP_Controller.
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD has service with serviceId: WANIPv6Firewall1
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_1-zz-zz-zz has parent UPnP::ControlPoint::Device=HASH(0x9e88c50)
2021.04.04 12:34:26 4: UPNPController: discovery message of InternetGatewayDeviceV2 - FRITZ!Box 7590 (UI) with UDN: uuid:75802409-bccb-40e7-9f6c-444E6D5B9ECD action: deviceAdded
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: alive-notify message device uuid:75802409-bccb-40e7-9f6c-444E6D5B9ECD received
2021.04.04 12:34:26 5: UPNPController: warning: main, Use of uninitialized value in string eq at ./FHEM/98_UPNPController.pm line 547.

2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6c-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6c-444E6D5B9ECD will be controlled with 192.168.178.1_49000_1
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6c-444E6D5B9ECD has service with serviceId: any1
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_1-zz is child with Id: uuid:75802409-bccb-40e7-9f6b-444E6D5B9ECD
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6b-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6b-444E6D5B9ECD will be controlled with 192.168.178.1_49000_1-zz
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6b-444E6D5B9ECD is not a parent
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6b-444E6D5B9ECD has service with serviceId: WANCommonIFC1
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_1-zz-zz has parent UPnP::ControlPoint::Device=HASH(0xa344a78)
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_1-zz-zz is child with Id: uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD will be controlled with 192.168.178.1_49000_1-zz-zz
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD is not a parent
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD has service with serviceId: WANDSLLinkC1
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD has service with serviceId: WANIPConn1
2021.04.04 12:34:26 4: UPNPController: subscribe: reading  192.168.178.1_49000_1-zz-zz-zs-WANIPConn1, uniquedevice 192.168.178.1_49000_1-zz-zz, service WANIPConn1
2021.04.04 12:34:26 4: UPNPController: WANIPConn1 unknown for UPNP_Controller.
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD has service with serviceId: WANIPv6Firewall1
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_1-zz-zz-zz has parent UPnP::ControlPoint::Device=HASH(0xa8814f0)
2021.04.04 12:34:26 4: UPNPController: discovery message of InternetGatewayDeviceV2 - FRITZ!Box 7590 (UI) with UDN: uuid:75802409-bccb-40e7-9f6c-444E6D5B9ECD action: deviceAdded
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: alive-notify message device uuid:75802409-bccb-40e7-9f6c-444E6D5B9ECD received
2021.04.04 12:34:26 5: UPNPController: warning: main, Use of uninitialized value in string eq at ./FHEM/98_UPNPController.pm line 547.

2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6c-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6c-444E6D5B9ECD will be controlled with 192.168.178.1_49000_1
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6c-444E6D5B9ECD has service with serviceId: any1
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_1-zz is child with Id: uuid:75802409-bccb-40e7-9f6b-444E6D5B9ECD
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6b-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6b-444E6D5B9ECD will be controlled with 192.168.178.1_49000_1-zz
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6b-444E6D5B9ECD is not a parent
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6b-444E6D5B9ECD has service with serviceId: WANCommonIFC1
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_1-zz-zz has parent UPnP::ControlPoint::Device=HASH(0xa805318)
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_1-zz-zz is child with Id: uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD will be controlled with 192.168.178.1_49000_1-zz-zz
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD is not a parent
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD has service with serviceId: WANDSLLinkC1
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD has service with serviceId: WANIPConn1
2021.04.04 12:34:26 4: UPNPController: subscribe: reading  192.168.178.1_49000_1-zz-zz-zs-WANIPConn1, uniquedevice 192.168.178.1_49000_1-zz-zz, service WANIPConn1
2021.04.04 12:34:26 4: UPNPController: WANIPConn1 unknown for UPNP_Controller.
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-9f6a-444E6D5B9ECD has service with serviceId: WANIPv6Firewall1
2021.04.04 12:34:26 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_1-zz-zz-zz has parent UPnP::ControlPoint::Device=HASH(0xa356b68)

2021.04.04 12:34:29 4: UPNPController: discovery message of FRITZ!Box 7590 (UI) with UDN: uuid:75802409-bccb-40e7-8e6c-444E6D5B9ECD action: deviceAdded
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: alive-notify message device uuid:75802409-bccb-40e7-8e6c-444E6D5B9ECD received
2021.04.04 12:34:29 5: UPNPController: warning: main, Use of uninitialized value in string eq at ./FHEM/98_UPNPController.pm line 547.

2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-8e6c-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-8e6c-444E6D5B9ECD will be controlled with 192.168.178.1_49000_2
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-8e6c-444E6D5B9ECD has service with serviceId: any1
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_2-zz is child with Id: uuid:76802409-bccb-40e7-8e6b-444E6D5B9ECD
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6b-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6b-444E6D5B9ECD will be controlled with 192.168.178.1_49000_2-zz
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6b-444E6D5B9ECD is not a parent
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6b-444E6D5B9ECD has service with serviceId: WANCommonIFC1
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_2-zz-zz has parent UPnP::ControlPoint::Device=HASH(0x9ef4708)
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_2-zz-zz is child with Id: uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD will be controlled with 192.168.178.1_49000_2-zz-zz
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD is not a parent
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD has service with serviceId: WANDSLLinkC1
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD has service with serviceId: WANIPConn1
2021.04.04 12:34:29 4: UPNPController: subscribe: reading  192.168.178.1_49000_2-zz-zz-zs-WANIPConn1, uniquedevice 192.168.178.1_49000_2-zz-zz, service WANIPConn1
2021.04.04 12:34:29 4: UPNPController: WANIPConn1 unknown for UPNP_Controller.
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD has service with serviceId: WANIPv6Firewall1
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_2-zz-zz-zz has parent UPnP::ControlPoint::Device=HASH(0x9e97578)
2021.04.04 12:34:29 4: UPNPController: discovery message of FRITZ!Box 7590 (UI) with UDN: uuid:75802409-bccb-40e7-8e6c-444E6D5B9ECD action: deviceAdded
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: alive-notify message device uuid:75802409-bccb-40e7-8e6c-444E6D5B9ECD received
2021.04.04 12:34:29 5: UPNPController: warning: main, Use of uninitialized value in string eq at ./FHEM/98_UPNPController.pm line 547.

2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-8e6c-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-8e6c-444E6D5B9ECD will be controlled with 192.168.178.1_49000_2
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-8e6c-444E6D5B9ECD has service with serviceId: any1
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_2-zz is child with Id: uuid:76802409-bccb-40e7-8e6b-444E6D5B9ECD
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6b-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6b-444E6D5B9ECD will be controlled with 192.168.178.1_49000_2-zz
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6b-444E6D5B9ECD is not a parent
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6b-444E6D5B9ECD has service with serviceId: WANCommonIFC1
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_2-zz-zz has parent UPnP::ControlPoint::Device=HASH(0x9e95b58)
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_2-zz-zz is child with Id: uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD will be controlled with 192.168.178.1_49000_2-zz-zz
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD is not a parent
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD has service with serviceId: WANDSLLinkC1
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD has service with serviceId: WANIPConn1
2021.04.04 12:34:29 4: UPNPController: subscribe: reading  192.168.178.1_49000_2-zz-zz-zs-WANIPConn1, uniquedevice 192.168.178.1_49000_2-zz-zz, service WANIPConn1
2021.04.04 12:34:29 4: UPNPController: WANIPConn1 unknown for UPNP_Controller.
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD has service with serviceId: WANIPv6Firewall1
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_2-zz-zz-zz has parent UPnP::ControlPoint::Device=HASH(0x9e866d8)
2021.04.04 12:34:29 4: UPNPController: discovery message of FRITZ!Box 7590 (UI) with UDN: uuid:75802409-bccb-40e7-8e6c-444E6D5B9ECD action: deviceAdded
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: alive-notify message device uuid:75802409-bccb-40e7-8e6c-444E6D5B9ECD received
2021.04.04 12:34:29 5: UPNPController: warning: main, Use of uninitialized value in string eq at ./FHEM/98_UPNPController.pm line 547.

2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-8e6c-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-8e6c-444E6D5B9ECD will be controlled with 192.168.178.1_49000_2
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:75802409-bccb-40e7-8e6c-444E6D5B9ECD has service with serviceId: any1
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_2-zz is child with Id: uuid:76802409-bccb-40e7-8e6b-444E6D5B9ECD
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6b-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6b-444E6D5B9ECD will be controlled with 192.168.178.1_49000_2-zz
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6b-444E6D5B9ECD is not a parent
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6b-444E6D5B9ECD has service with serviceId: WANCommonIFC1
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_2-zz-zz has parent UPnP::ControlPoint::Device=HASH(0x9e90030)
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_2-zz-zz is child with Id: uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD will be controlled with 192.168.178.1_49000_2-zz-zz
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD is not a parent
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD has service with serviceId: WANDSLLinkC1
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD has service with serviceId: WANIPConn1
2021.04.04 12:34:29 4: UPNPController: subscribe: reading  192.168.178.1_49000_2-zz-zz-zs-WANIPConn1, uniquedevice 192.168.178.1_49000_2-zz-zz, service WANIPConn1
2021.04.04 12:34:29 4: UPNPController: WANIPConn1 unknown for UPNP_Controller.
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:76802409-bccb-40e7-8e6a-444E6D5B9ECD has service with serviceId: WANIPv6Firewall1
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device 192.168.178.1_49000_2-zz-zz-zz has parent UPnP::ControlPoint::Device=HASH(0x9ed1fa0)
2021.04.04 12:34:29 4: UPNPController: discovery message of FRITZ!Box 7590 (UI) with UDN: uuid:535502409-bccb-40e7-8e6c-444E6D5B9ECD action: deviceAdded
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: alive-notify message device uuid:535502409-bccb-40e7-8e6c-444E6D5B9ECD received
2021.04.04 12:34:29 5: UPNPController: warning: main, Use of uninitialized value in string eq at ./FHEM/98_UPNPController.pm line 547.

2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:535502409-bccb-40e7-8e6c-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:535502409-bccb-40e7-8e6c-444E6D5B9ECD will be controlled with 192.168.178.1_49000_3
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:535502409-bccb-40e7-8e6c-444E6D5B9ECD has service with serviceId: avmnexus
2021.04.04 12:34:29 4: UPNPController: discovery message of FRITZ!Box 7590 (UI) with UDN: uuid:95802409-bccb-40e7-8e6c-444E6D5B9ECD action: deviceAdded
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: alive-notify message device uuid:95802409-bccb-40e7-8e6c-444E6D5B9ECD received
2021.04.04 12:34:29 5: UPNPController: warning: main, Use of uninitialized value in string eq at ./FHEM/98_UPNPController.pm line 547.

2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:95802409-bccb-40e7-8e6c-444E6D5B9ECD found. Check for processing
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:95802409-bccb-40e7-8e6c-444E6D5B9ECD will be controlled with 192.168.178.1_49000_4
2021.04.04 12:34:29 5: UPNPController: UPNP_Controller: device uuid:95802409-bccb-40e7-8e6c-444E6D5B9ECD has service with serviceId: l2tpv31

2021.04.04 12:34:38 4: UPNPController: try to renew subscriptions for services, device UPNP_Controller with timeout: 100

2021.04.04 12:35:27 4: UPNPController: discovery message of AVM FRITZ!Mediaserver with UDN: uuid:fa095ecc-e13e-40e7-8e6c-444e6d5b9ecd action: deviceAdded
2021.04.04 12:35:27 5: UPNPController: UPNP_Controller: alive-notify message device uuid:fa095ecc-e13e-40e7-8e6c-444e6d5b9ecd received
2021.04.04 12:35:27 5: UPNPController: warning: main, Use of uninitialized value in string eq at ./FHEM/98_UPNPController.pm line 547.

2021.04.04 12:35:27 5: UPNPController: UPNP_Controller: device uuid:fa095ecc-e13e-40e7-8e6c-444e6d5b9ecd found. Check for processing
2021.04.04 12:35:27 5: UPNPController: UPNP_Controller: device uuid:fa095ecc-e13e-40e7-8e6c-444e6d5b9ecd will be controlled with 192.168.178.1_49000_5
2021.04.04 12:35:27 5: UPNPController: UPNP_Controller: device uuid:fa095ecc-e13e-40e7-8e6c-444e6d5b9ecd has service with serviceId: ContentDirectory
2021.04.04 12:35:27 5: UPNPController: UPNP_Controller: device uuid:fa095ecc-e13e-40e7-8e6c-444e6d5b9ecd has service with serviceId: ConnectionManager
2021.04.04 12:35:27 5: UPNPController: UPNP_Controller: device uuid:fa095ecc-e13e-40e7-8e6c-444e6d5b9ecd has service with serviceId: X_MS_MediaReceiverRegistrar
2021.04.04 12:35:27 5: UPNPController: UPNP_Controller: device uuid:fa095ecc-e13e-40e7-8e6c-444e6d5b9ecd has service with serviceId: AVM_ServerStatus
2021.04.04 12:35:36 5: UPNPController: warning: main, Use of uninitialized value $filemtime in concatenation (.) or string at ./FHEM/70_WINCONNECT.pm line 252.

2021.04.04 12:35:36 5: UPNPController: warning: main, Use of uninitialized value $filemtime in string eq at ./FHEM/70_WINCONNECT.pm line 281.

2021.04.04 12:35:36 5: UPNPController: warning: main, Use of uninitialized value $value in string eq at fhem.pl line 4865.

2021.04.04 12:35:41 4: UPNPController: try to renew subscriptions for services, device UPNP_Controller with timeout: 100

2021.04.04 12:36:38 5: UPNPController: warning: main, Use of uninitialized value $filemtime in concatenation (.) or string at ./FHEM/70_WINCONNECT.pm line 252.

2021.04.04 12:36:38 5: UPNPController: warning: main, Use of uninitialized value $filemtime in string eq at ./FHEM/70_WINCONNECT.pm line 281.

2021.04.04 12:36:38 5: UPNPController: warning: main, Use of uninitialized value $value in string eq at fhem.pl line 4865.

2021.04.04 12:36:44 4: UPNPController: try to renew subscriptions for services, device UPNP_Controller with timeout: 100



Hier müsste das Streamen beginnen (EDIT: nun im Anhang):


EDIT:
Irgendwie bekomme ich das Log und meine Message nicht rein. Habe nun das zweite Log gelöscht und in den Anhang gepackt.

Mit Eki's-Modul ist es ähnlich (siehe https://forum.fhem.de/index.php/topic,118837.msg1137353.html#msg1137353). Ein klein wenig mehr scheint damit zu gehen.


EDIT 2:
Habe Eki's Modul gerade nochmal angeschaut. Man sieht eigentlich bzgl. des Status des TVs gar nichts (wie z.B. on/off, Lautstärke, Mute). Mir ist es nur damals gelungen das Gerät auszuschalten. Jetzt gerade habe ich es nicht hinbekommen.

Viele Grüße
Dieter

[KölnSolar]

Hi Dieter,
um diese Info gings mir

Jedoch funktioniert das umgekehrt vom Mediaplayer zum TV.

Der TV akzeptiert also actions. Nur unsere nicht. 

Wireshark ist mir übrigens ein Begriff, hatte das Tool aber nie verwendet.

Mist. So könnten wir dem Unterschied auf den Grund gehen.

Eki: Liest Du mit ? Ich denke Du bist firm in Wireshark u. tcpdump.

Habe die Änderungen an den Attributen envNamespace u. envPrefix, wie in dem Post https://forum.fhem.de/index.php/topic,118837.msg1137371.html#msg1137371 von Dir beschrieben vorgenommen

Das bewirkt bei den Samsungs, dass das streaming(die action) funktioniert. Das ging aber bei Deinem immer noch nicht, oder etwa nicht probiert?

FB-Aktivitäten sieht man im Mediaplayer nicht

Aber in dem alten Post scheinbar(wider Erwarten) volume-Änderungen vom Mediaplayer

Code Auswählen Erweitern

2021.03.21 12:17:02 5: UPNPController: incoming message; will be processed by perlupnp handleOnce
2021.03.21 12:17:02 5: UPNPController: Received event: by 192.168.178.61_2870-zs-RenderingControl UPnP::ControlPoint::Service=HASH(0xb077848) $VAR1 = 'LastChange';
$VAR2 = '<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Volume channel="Master" val="20"/><VolumeDB channel="Master" val="-19661"/></InstanceID></Event>';

2021.03.21 12:17:02 5: UPNPController: Received event: by 192.168.178.61_2870-zs-RenderingControl property: <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Volume channel="Master" val="20"/><VolumeDB channel="Master" val="-19661"/></InstanceID></Event>
2021.03.21 12:17:02 5: UPNP_Controller: dispatch RenderingControl|192.168.178.61_2870|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Volume channel="Master" val="20"/><VolumeDB channel="Master" val="-19661"/></InstanceID></Event>
2021.03.21 12:17:02 5: DLNAController: message from UPNP_Controller, message: RenderingControl|192.168.178.61_2870|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Volume channel="Master" val="20"/><VolumeDB channel="Master" val="-19661"/></InstanceID></Event>
2021.03.21 12:17:02 4: DLNAController: message parameters: RenderingControl, 192.168.178.61_2870, LastChange, <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Volume channel="Master" val="20"/><VolumeDB channel="Master" val="-19661"/></InstanceID></Event>
2021.03.21 12:17:02 5: UPNPController: UPNPSocket-UPNP_Controller-44169, received subscription event: event was processed by subscriptionCallback ,
Also nur nicht von der Fb ?

Hier müsste das Streamen beginnen:

Nicht ganz, das ist das renewal nebst Selbstheilung.

Code Auswählen Erweitern


2021.04.04 12:37:47 5: UPNPController: Received event: by 192.168.178.61_2870-zs-AVTransport property: <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/"><InstanceID val="0"><AVTransportURI val="http://192.168.178.38:10246/MDEServer/FF42E307-A9BC-4F6F-932C-E7CF76CE84A3/1000.mp3"/><NumberOfTracks val="1"/><AVTransportURIMetaData val="&lt;DIDL-Lite xmlns:dc=&quot;http://purl.org/dc/elements/1.1/&quot; xmlns:upnp=&quot;urn:schemas-upnp-org:metadata-1-0/upnp/&quot; xmlns=&quot;urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/&quot; xmlns:microsoft=&quot;urn:schemas-microsoft-com:WMPNSS-1-0/&quot; xmlns:dlna=&quot;urn:schemas-dlna-org:metadata-1-0/&quot;&gt;&lt;item id=&quot;1000&quot; restricted=&quot;1&quot; parentID=&quot;0&quot; microsoft:cpId=&quot;{ACB39F48-C5EA-420C-B1B9-B094B7139722}&quot; microsoft:trackId=&quot;1&quot;&gt;&lt;dc:title&gt;Violin Concertos KV 364-190&lt;/dc:title&gt;&lt;dc:creator&gt;Mozart&lt;/dc:creator&gt;&lt;res size=&quot;73025024&quot; duration=&quot;0:54:29.015&quot; bitrate=&quot;22338&quot; protocolInfo=&quot;http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_FLAGS=01700000000000000000000000000000&quot; sampleFrequency=&quot;44100&quot; bitsPerSample=&quot;16&quot; nrAudioChannels=&quot;2&quot; microsoft:codec=&quot;{00000055-0000-0010-8000-00AA00389B71}&quot;&gt;http://192.168.178.38:10246/MDEServer/FF42E307-A9BC-4F6F-932C-E7CF76CE84A3/1000.mp3&lt;/res&gt;&lt;res duration=&quot;0:54:29.015&quot; bitrate=&quot;22338&quot; protocolInfo=&quot;http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=10;DLNA.ORG_FLAGS=01700000000000000000000000000000&quot; sampleFrequency=&quot;44100&quot; bitsPerSample=&quot;16&quot; nrAudioChannels=&quot;2&quot; microsoft:codec=&quot;{00000055-0000-0010-8000-00AA00389B71}&quot;&gt;http://192.168.178.38:10246/MDEServer/FF42E307-A9BC-4F6F-932C-E7CF76CE84A3/1000.mp3?formatID=00000008-A9AF-4584-84E2-55BFEF0A7D7E&lt;/res&gt;&lt;res duration=&quot;0:54:29.015&quot; bitrate=&quot;176400&quot; protocolInfo=&quot;http-get:*:audio/L16;rate=44100;channels=2:DLNA.ORG_PN=LPCM;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01700000000000000000000000000000&quot; sampleFrequency=&quot;44100&quot; bitsPerSample=&quot;16&quot; nrAudioChannels=&quot;2&quot; microsoft:codec=&quot;{00000001-0000-0010-8000-00AA00389B71}&quot;&gt;http://192.168.178.38:10246/MDEServer/FF42E307-A9BC-4F6F-932C-E7CF76CE84A3/1000.mp3?formatID=00000033-A9AF-4584-84E2-55BFEF0A7D7E&lt;/res&gt;&lt;res duration=&quot;0:54:29.015&quot; bitrate=&quot;8000&quot; protocolInfo=&quot;http-get:*:audio/x-ms-wma:DLNA.ORG_PN=WMABASE;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01700000000000000000000000000000&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; microsoft:codec=&quot;{00000161-0000-0010-8000-00AA00389B71}&quot;&gt;http://192.168.178.38:10246/MDEServer/FF42E307-A9BC-4F6F-932C-E7CF76CE84A3/1000.wma?formatID=000000BA-A9AF-4584-84E2-55BFEF0A7D7E&lt;/res&gt;&lt;res duration=&quot;0:54:29.015&quot; bitrate=&quot;24000&quot; protocolInfo=&quot;
zeigt mir, dass xml nicht richtig aufgelöst wird. Auch bei mir nicht.  Liegt wohl irgendwo im Perl-LWP-Modul u. ging dann noch nie.  Da bekäme man ein paar Informationenen womit sich readings füllen lassen. Ich gucke....

Grüße Markus
Edit: Nach Deinem edit sehe ich dann auch das streaming des Mediaplayers.
Das

Code Auswählen Erweitern

2021.04.04 12:39:39 3: UPNPController: handleOnce failed, Not a HASH reference at ./FHEM/98_DLNAController.pm line 742.versuch ich mal nachzuvollziehen.

[RockFan]

Hi Markus,

hier noch ein Update bzgl. Deiner Kommentare von gestern:

Das bewirkt bei den Samsungs, dass das streaming(die action) funktioniert. Das ging aber bei Deinem immer noch nicht, oder etwa nicht probiert?

Nach dem Setzen der beiden Attribute habe ich das Streaming von FHEM aus noch nicht versucht und gerade nachgeholt. Dazu habe ich wieder den Default "Stream" (google translate) genommen und siehe da; es kommt etwas am TV an. Ich hätte zwar "Hallo" erwartet, war aber irgend etwas kurzes und unverständliches. Ich denke das ist aber egal, es ging ja nur darum, ob es grundsätzlich klappt
Dann habe ich auch wieder den Versuch über das Kommando speak probiert. Auch mit Erfolg; mein verwendetes "Hallo" ergab allerdings auch wieder möglicherweise gleiche unverständliche Ausgabe.

Also nur nicht von der Fb ?

Hier hast du mich vielleicht falsch verstanden: In FHEM kommt nichts von der FB aber auch nicht Änderungen bzgl. Lautstärke oder Mute im Mediaplayer an. Änderungen vom Mediaplayer kommen aber am TV an.

Soll ich eigentlich den Timeout solange wir noch testen auf 100 belassen oder lieber wieder auf 1800 oder 300 setzen?


Viele Grüße
Dieter

[KölnSolar]

Hi Dieter,

das

Auch mit Erfolg;

klingt doch mal gut. Also braucht es dem Samsung vergleichbare Einstellungen. Könntest dann mal ein Bild(nutze ich für Anruferinfo, FHEM-Status, Doorcam) probieren. Und natürlich auch Lautstärke(wenns nicht klappt, wäre interessant, ob der Fehlercode immer noch 501 ist).

Hier hast du mich vielleicht falsch verstanden:

Mag sein, aber im Log seh ich ja events: 12:13:52 subscription mit volume =0, 12:16:56 volume=1, 12:17:02 volume=20 Irgendwoher müssen die Änderungen ja gekommen sein.

Kannst Du so lassen. Mit der nächsten Version ist es dann eh wieder Weg. Und die wird relativ flott kommen, da ich wg.

Code Auswählen Erweitern


2021.04.04 12:39:39 3: UPNPController: handleOnce failed, Not a HASH reference at ./FHEM/98_DLNAController.pm line 742.
drauf gestoßen bin, dass die bisher eingesetzte library zum parsen des xml, nicht mehr eingesetzt werden sollte. Also muss ich eine andere lib einbauen, die völlig "anders" funktioniert.

neuer Zwischenstand
- online/offline funktioniert
- renewal der subscription läuft auf Fehler 412
  wird aber durch das Modul "geheilt"
- keine events v. RenderingControl(volume, mute...) wg. fehlerhafter xml-description(möglicherweise nur nicht bei FB-Befehl ?)
- events f. AVTransport(streaming)
- ausgelöste actions laufen auf Fehler 501mit den "Samsung-Attributen".  streaming funktioniert(volume,mute noch zu testen)

Grüße Markus

Edit: Was ich noch vergessen hatte: Wie sieht die SID in den service-readings des TV im UPNPController aus ?

[RockFan]

Wie sieht die SID in den service-readings des TV im UPNPController aus ?

Code Auswählen Erweitern


192.168.178.61_2870-zs-AVTransport          SID: uuid:14191330-ff65-121f-8000-de0beef2c0cd timeout: 300 property: LastChange     2021-04-07 19:31:54
192.168.178.61_2870-zs-RenderingControl SID: uuid:1416fd66-ff65-121f-8000-de0beef2c0cd timeout: 300 property: LastChange       2021-04-07 19:31:54


Viele Grüße
Dieter

[KölnSolar]

Hi Dieter,
guck mal bitte hier. Wenn Du das eingebaut bekommst, haben wir wenigstens mal einen "klaren" Blick ohne dass uns geändeerte IPs/Ports zur Verzweiflung bringen.
Grüße Markus

[RockFan]

Hallo Markus,

ich hoffe dass bei dir nach dem Plattencrash wieder alles läuft!

Die von Dir verlinkte Änderung habe ich eingebaut und gleich mal laufen lassen. Änderungen über die FB kommen in FHEM noch nicht an. Dafür aber Lautstärkeänderungen in FHEM. Die machen sich nun am TV bemerkbar und der Stream "Hello" ist nun auch zu verstehen am Gerät.

EDIT: Logs nach ein paar Tests findest du im Anhang.

EDIT2:
Das Thema Mute über FHEM ist für mich verwirrend. Es gibt Versuche, in denen zumindest das Reading im DLNAcontroller die Aktion "mute on" auch im Reading mute widerspiegelt. Aber nur manchmal!?
Am TV sehe ich das Mute-Zeichen nicht; muss aber nichts heißen, da ich die Lautstärkeänderung auch nicht sehe, sondern nur das Ergebnis, wenn ich die Lautstärke wieder mit der FB ändere.

Hier ein paar Logs zu "Mute":

Code Auswählen Erweitern


2021.04.17 12:22:10 4: UPNPController: discovery message of ALDISUED TV with UDN: uuid:13cae598-3cca-165a-8000-0009dfbd33fb action: deviceAdded
2021.04.17 12:22:10 5: UPNPController: UPNP_Controller: alive-notify message device uuid:13cae598-3cca-165a-8000-0009dfbd33fb received
2021.04.17 12:22:10 5: UPNPController: warning: main, Use of uninitialized value in string eq at ./FHEM/98_UPNPController.pm line 547.

2021.04.17 12:22:10 5: UPNPController: UPNP_Controller: device uuid:13cae598-3cca-165a-8000-0009dfbd33fb found. Check for processing
2021.04.17 12:22:10 5: UPNPController: UPNP_Controller: device uuid:13cae598-3cca-165a-8000-0009dfbd33fb will be controlled with 192.168.178.61_2870
2021.04.17 12:22:10 5: UPNPController: UPNP_Controller: device uuid:13cae598-3cca-165a-8000-0009dfbd33fb has service with serviceId: RenderingControl
2021.04.17 12:22:10 4: UPNPController: subscribe: reading  192.168.178.61_2870-zs-RenderingControl, uniquedevice 192.168.178.61_2870, service RenderingControl
2021.04.17 12:22:10 5: UPNPController: RenderingControl: urn:schemas-upnp-org:service:RenderingControl:1 found. OK.
2021.04.17 12:22:10 5: UPNPController: RenderingControl: urn:schemas-upnp-org:service:RenderingControl:1 found. OK.
2021.04.17 12:22:10 4: UPNPController: subscribe: reading  192.168.178.61_2870-zs-RenderingControl, uniquedevice 192.168.178.61_2870, service RenderingControl timeout: 300
2021.04.17 12:22:10 5: UPNPController: UPNP_Controller: device uuid:13cae598-3cca-165a-8000-0009dfbd33fb has service with serviceId: ConnectionManager
2021.04.17 12:22:10 5: UPNPController: UPNP_Controller: device uuid:13cae598-3cca-165a-8000-0009dfbd33fb has service with serviceId: AVTransport
2021.04.17 12:22:10 4: UPNPController: subscribe: reading  192.168.178.61_2870-zs-AVTransport, uniquedevice 192.168.178.61_2870, service AVTransport
2021.04.17 12:22:10 5: UPNPController: AVTransport: urn:schemas-upnp-org:service:AVTransport:1 found. OK.
2021.04.17 12:22:10 5: UPNPController: AVTransport: urn:schemas-upnp-org:service:AVTransport:1 found. OK.
2021.04.17 12:22:10 4: UPNPController: subscribe: reading  192.168.178.61_2870-zs-AVTransport, uniquedevice 192.168.178.61_2870, service AVTransport timeout: 300
2021.04.17 12:22:10 5: UPNP_Controller: dispatch AVTransport|192.168.178.61_2870|online|urn:schemas-upnp-org:metadata-1-0/AVT
2021.04.17 12:22:10 5: DLNAController: message from UPNP_Controller, message: AVTransport|192.168.178.61_2870|online|urn:schemas-upnp-org:metadata-1-0/AVT
2021.04.17 12:22:10 4: DLNAController: message parameters: AVTransport, 192.168.178.61_2870, online, urn:schemas-upnp-org:metadata-1-0/AVT
2021.04.17 12:22:10 5: UPNPController: Received event: by 192.168.178.61_2870-zs-RenderingControl UPnP::ControlPoint::Service=HASH(0xa0c26a8) $VAR1 = 'LastChange';
$VAR2 = '<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="0"/><VolumeDB channel="Master" val="-32768"/><PresetNameList val="FactoryDefaults"/></InstanceID></Event>';

2021.04.17 12:22:10 5: UPNPController: Received event: by 192.168.178.61_2870-zs-RenderingControl property: <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="0"/><VolumeDB channel="Master" val="-32768"/><PresetNameList val="FactoryDefaults"/></InstanceID></Event>
2021.04.17 12:22:10 5: UPNP_Controller: dispatch RenderingControl|192.168.178.61_2870|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="0"/><VolumeDB channel="Master" val="-32768"/><PresetNameList val="FactoryDefaults"/></InstanceID></Event>
2021.04.17 12:22:10 5: DLNAController: message from UPNP_Controller, message: RenderingControl|192.168.178.61_2870|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="0"/><VolumeDB channel="Master" val="-32768"/><PresetNameList val="FactoryDefaults"/></InstanceID></Event>
2021.04.17 12:22:10 4: DLNAController: message parameters: RenderingControl, 192.168.178.61_2870, LastChange, <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="0"/><VolumeDB channel="Master" val="-32768"/><PresetNameList val="FactoryDefaults"/></InstanceID></Event>
2021.04.17 12:22:10 5: UPNPController: Received event: by 192.168.178.61_2870-zs-AVTransport UPnP::ControlPoint::Service=HASH(0x9a63108) $VAR1 = 'LastChange';
$VAR2 = '&lt;Event xmlns=&quot;urn:schemas-upnp-org:metadata-1-0/AVT/&quot;&gt;&lt;InstanceID val=&quot;0&quot;&gt;&lt;AVTransportURI val=&quot;&quot;/&gt;&lt;NumberOfTracks val=&quot;0&quot;/&gt;&lt;AVTransportURIMetaData val=&quot;&quot;/&gt;&lt;PlaybackStorageMedium val=&quot;NONE&quot;/&gt;&lt;CurrentMediaCategory val=&quot;NO_MEDIA&quot;/&gt;&lt;PossiblePlaybackStorageMedia val=&quot;NONE,NETWORK&quot;/&gt;&lt;RecordStorageMedium val=&quot;NOT_IMPLEMENTED&quot;/&gt;&lt;PossibleRecordStorageMedia val=&quot;NOT_IMPLEMENTED&quot;/&gt;&lt;RecordMediumWriteStatus val=&quot;NOT_IMPLEMENTED&quot;/&gt;&lt;NextAVTransportURI val=&quot;NOT_IMPLEMENTED&quot;/&gt;&lt;NextAVTransportURIMetaData val=&quot;NOT_IMPLEMENTED&quot;/&gt;&lt;CurrentPlayMode val=&quot;NORMAL&quot;/&gt;&lt;CurrentRecordQualityMode val=&quot;NOT_IMPLEMENTED&quot;/&gt;&lt;PossibleRecordQualityModes val=&quot;NOT_IMPLEMENTED&quot;/&gt;&lt;TransportState val=&quot;NO_MEDIA_PRESENT&quot;/&gt;&lt;TransportStatus val=&quot;OK&quot;/&gt;&lt;TransportPlaySpeed val=&quot;1&quot;/&gt;&lt;CurrentTrack val=&quot;0&quot;/&gt;&lt;CurrentTrackDuration val=&quot;00:00:00&quot;/&gt;&lt;CurrentTrackMetaData val=&quot;&quot;/&gt;&lt;CurrentTrackURI val=&quot;&quot;/&gt;&lt;CurrentMediaDuration val=&quot;00:00:00&quot;/&gt;&lt;CurrentTransportActions val=&quot;&quot;/&gt;&lt;/InstanceID&gt;&lt;/Event&gt;';

2021.04.17 12:22:10 5: UPNPController: Received event: by 192.168.178.61_2870-zs-AVTransport property: <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/"><InstanceID val="0"><AVTransportURI val=""/><NumberOfTracks val="0"/><AVTransportURIMetaData val=""/><PlaybackStorageMedium val="NONE"/><CurrentMediaCategory val="NO_MEDIA"/><PossiblePlaybackStorageMedia val="NONE,NETWORK"/><RecordStorageMedium val="NOT_IMPLEMENTED"/><PossibleRecordStorageMedia val="NOT_IMPLEMENTED"/><RecordMediumWriteStatus val="NOT_IMPLEMENTED"/><NextAVTransportURI val="NOT_IMPLEMENTED"/><NextAVTransportURIMetaData val="NOT_IMPLEMENTED"/><CurrentPlayMode val="NORMAL"/><CurrentRecordQualityMode val="NOT_IMPLEMENTED"/><PossibleRecordQualityModes val="NOT_IMPLEMENTED"/><TransportState val="NO_MEDIA_PRESENT"/><TransportStatus val="OK"/><TransportPlaySpeed val="1"/><CurrentTrack val="0"/><CurrentTrackDuration val="00:00:00"/><CurrentTrackMetaData val=""/><CurrentTrackURI val=""/><CurrentMediaDuration val="00:00:00"/><CurrentTransportActions val=""/></InstanceID></Event>
2021.04.17 12:22:10 5: UPNP_Controller: dispatch AVTransport|192.168.178.61_2870|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/"><InstanceID val="0"><AVTransportURI val=""/><NumberOfTracks val="0"/><AVTransportURIMetaData val=""/><PlaybackStorageMedium val="NONE"/><CurrentMediaCategory val="NO_MEDIA"/><PossiblePlaybackStorageMedia val="NONE,NETWORK"/><RecordStorageMedium val="NOT_IMPLEMENTED"/><PossibleRecordStorageMedia val="NOT_IMPLEMENTED"/><RecordMediumWriteStatus val="NOT_IMPLEMENTED"/><NextAVTransportURI val="NOT_IMPLEMENTED"/><NextAVTransportURIMetaData val="NOT_IMPLEMENTED"/><CurrentPlayMode val="NORMAL"/><CurrentRecordQualityMode val="NOT_IMPLEMENTED"/><PossibleRecordQualityModes val="NOT_IMPLEMENTED"/><TransportState val="NO_MEDIA_PRESENT"/><TransportStatus val="OK"/><TransportPlaySpeed val="1"/><CurrentTrack val="0"/><CurrentTrackDuration val="00:00:00"/><CurrentTrackMetaData val=""/><CurrentTrackURI val=""/><CurrentMediaDuration val="00:00:00"/><CurrentTransportActions val=""/></InstanceID></Event>
2021.04.17 12:22:10 5: DLNAController: message from UPNP_Controller, message: AVTransport|192.168.178.61_2870|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/"><InstanceID val="0"><AVTransportURI val=""/><NumberOfTracks val="0"/><AVTransportURIMetaData val=""/><PlaybackStorageMedium val="NONE"/><CurrentMediaCategory val="NO_MEDIA"/><PossiblePlaybackStorageMedia val="NONE,NETWORK"/><RecordStorageMedium val="NOT_IMPLEMENTED"/><PossibleRecordStorageMedia val="NOT_IMPLEMENTED"/><RecordMediumWriteStatus val="NOT_IMPLEMENTED"/><NextAVTransportURI val="NOT_IMPLEMENTED"/><NextAVTransportURIMetaData val="NOT_IMPLEMENTED"/><CurrentPlayMode val="NORMAL"/><CurrentRecordQualityMode val="NOT_IMPLEMENTED"/><PossibleRecordQualityModes val="NOT_IMPLEMENTED"/><TransportState val="NO_MEDIA_PRESENT"/><TransportStatus val="OK"/><TransportPlaySpeed val="1"/><CurrentTrack val="0"/><CurrentTrackDuration val="00:00:00"/><CurrentTrackMetaData val=""/><CurrentTrackURI val=""/><CurrentMediaDuration val="00:00:00"/><CurrentTransportActions val=""/></InstanceID></Event>
2021.04.17 12:22:10 4: DLNAController: message parameters: AVTransport, 192.168.178.61_2870, LastChange, <Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/"><InstanceID val="0"><AVTransportURI val=""/><NumberOfTracks val="0"/><AVTransportURIMetaData val=""/><PlaybackStorageMedium val="NONE"/><CurrentMediaCategory val="NO_MEDIA"/><PossiblePlaybackStorageMedia val="NONE,NETWORK"/><RecordStorageMedium val="NOT_IMPLEMENTED"/><PossibleRecordStorageMedia val="NOT_IMPLEMENTED"/><RecordMediumWriteStatus val="NOT_IMPLEMENTED"/><NextAVTransportURI val="NOT_IMPLEMENTED"/><NextAVTransportURIMetaData val="NOT_IMPLEMENTED"/><CurrentPlayMode val="NORMAL"/><CurrentRecordQualityMode val="NOT_IMPLEMENTED"/><PossibleRecordQualityModes val="NOT_IMPLEMENTED"/><TransportState val="NO_MEDIA_PRESENT"/><TransportStatus val="OK"/><TransportPlaySpeed val="1"/><CurrentTrack val="0"/><CurrentTrackDuration val="00:00:00"/><CurrentTrackMetaData val=""/><CurrentTrackURI val=""/><CurrentMediaDuration val="00:00:00"/><CurrentTransportActions val=""/></InstanceID></Event>

2021.04.17 12:22:47 5: UPNPController: UPNP_Controller, 192.168.178.61_2870, RenderingControl, SetMute, 0 Master 1
2021.04.17 12:22:47 5: UPNPController: RenderingControl: urn:schemas-upnp-org:service:RenderingControl:1 found. OK.
2021.04.17 12:22:47 5: UPNPController: Received event: by 192.168.178.61_2870-zs-RenderingControl UPnP::ControlPoint::Service=HASH(0xa0c26a8) $VAR1 = 'LastChange';
$VAR2 = '&lt;Event xmlns=&quot;urn:schemas-upnp-org:metadata-1-0/RCS/&quot;&gt;&lt;InstanceID val=&quot;0&quot;&gt;&lt;Mute channel=&quot;Master&quot; val=&quot;1&quot;/&gt;&lt;/InstanceID&gt;&lt;/Event&gt;';

2021.04.17 12:22:47 5: UPNPController: Received event: by 192.168.178.61_2870-zs-RenderingControl property: <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="1"/></InstanceID></Event>
2021.04.17 12:22:47 5: UPNP_Controller: dispatch RenderingControl|192.168.178.61_2870|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="1"/></InstanceID></Event>
2021.04.17 12:22:47 5: DLNAController: message from UPNP_Controller, message: RenderingControl|192.168.178.61_2870|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="1"/></InstanceID></Event>
2021.04.17 12:22:47 4: DLNAController: message parameters: RenderingControl, 192.168.178.61_2870, LastChange, <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="1"/></InstanceID></Event>

2021.04.17 12:23:20 4: UPNPController: try to renew subscriptions for services, device UPNP_Controller with timeout: 100
2021.04.17 12:23:20 5: UPNPController: UPNP_Controller, 192.168.178.61_2870, RenderingControl, SetVolume, 0 Master 42
2021.04.17 12:23:20 5: UPNPController: RenderingControl: urn:schemas-upnp-org:service:RenderingControl:1 found. OK.
2021.04.17 12:23:20 5: UPNPController: Received event: by 192.168.178.61_2870-zs-RenderingControl UPnP::ControlPoint::Service=HASH(0xa0c26a8) $VAR1 = 'LastChange';
$VAR2 = '&lt;Event xmlns=&quot;urn:schemas-upnp-org:metadata-1-0/RCS/&quot;&gt;&lt;InstanceID val=&quot;0&quot;&gt;&lt;Mute channel=&quot;Master&quot; val=&quot;0&quot;/&gt;&lt;Volume channel=&quot;Master&quot; val=&quot;42&quot;/&gt;&lt;VolumeDB channel=&quot;Master&quot; val=&quot;-5243&quot;/&gt;&lt;/InstanceID&gt;&lt;/Event&gt;';

2021.04.17 12:23:20 5: UPNPController: Received event: by 192.168.178.61_2870-zs-RenderingControl property: <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="42"/><VolumeDB channel="Master" val="-5243"/></InstanceID></Event>
2021.04.17 12:23:20 5: UPNP_Controller: dispatch RenderingControl|192.168.178.61_2870|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="42"/><VolumeDB channel="Master" val="-5243"/></InstanceID></Event>
2021.04.17 12:23:20 5: DLNAController: message from UPNP_Controller, message: RenderingControl|192.168.178.61_2870|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="42"/><VolumeDB channel="Master" val="-5243"/></InstanceID></Event>
2021.04.17 12:23:20 4: DLNAController: message parameters: RenderingControl, 192.168.178.61_2870, LastChange, <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Mute channel="Master" val="0"/><Volume channel="Master" val="42"/><VolumeDB channel="Master" val="-5243"/></InstanceID></Event>
2021.04.17 12:23:21 5: UPNPController: UPNP_Controller, 192.168.178.61_2870, RenderingControl, SetVolume, 0 Master 16
2021.04.17 12:23:21 5: UPNPController: RenderingControl: urn:schemas-upnp-org:service:RenderingControl:1 found. OK.
2021.04.17 12:23:22 5: UPNPController: Received event: by 192.168.178.61_2870-zs-RenderingControl UPnP::ControlPoint::Service=HASH(0xa0c26a8) $VAR1 = 'LastChange';
$VAR2 = '&lt;Event xmlns=&quot;urn:schemas-upnp-org:metadata-1-0/RCS/&quot;&gt;&lt;InstanceID val=&quot;0&quot;&gt;&lt;Volume channel=&quot;Master&quot; val=&quot;16&quot;/&gt;&lt;VolumeDB channel=&quot;Master&quot; val=&quot;-22282&quot;/&gt;&lt;/InstanceID&gt;&lt;/Event&gt;';

2021.04.17 12:23:22 5: UPNPController: Received event: by 192.168.178.61_2870-zs-RenderingControl property: <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Volume channel="Master" val="16"/><VolumeDB channel="Master" val="-22282"/></InstanceID></Event>
2021.04.17 12:23:22 5: UPNP_Controller: dispatch RenderingControl|192.168.178.61_2870|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Volume channel="Master" val="16"/><VolumeDB channel="Master" val="-22282"/></InstanceID></Event>
2021.04.17 12:23:22 5: DLNAController: message from UPNP_Controller, message: RenderingControl|192.168.178.61_2870|LastChange|<Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Volume channel="Master" val="16"/><VolumeDB channel="Master" val="-22282"/></InstanceID></Event>
2021.04.17 12:23:22 4: DLNAController: message parameters: RenderingControl, 192.168.178.61_2870, LastChange, <Event xmlns="urn:schemas-upnp-org:metadata-1-0/RCS/"><InstanceID val="0"><Volume channel="Master" val="16"/><VolumeDB channel="Master" val="-22282"/></InstanceID></Event>

2021.04.17 12:23:52 5: UPNPController: UPNP_Controller, 192.168.178.61_2870, RenderingControl, SetMute, 0 Master 1
2021.04.17 12:23:52 5: UPNPController: RenderingControl: urn:schemas-upnp-org:service:RenderingControl:1 found. OK.

2021.04.17 12:24:13 5: UPNPController: warning: main, Use of uninitialized value $filemtime in concatenation (.) or string at ./FHEM/70_WINCONNECT.pm line 252.

2021.04.17 12:24:13 5: UPNPController: warning: main, Use of uninitialized value $filemtime in string eq at ./FHEM/70_WINCONNECT.pm line 281.

2021.04.17 12:24:13 5: UPNPController: warning: main, Use of uninitialized value $value in string eq at fhem.pl line 4879.

2021.04.17 12:24:20 4: UPNPController: try to renew subscriptions for services, device UPNP_Controller with timeout: 100
2021.04.17 12:24:23 5: UPNPController: UPNP_Controller, 192.168.178.61_2870, RenderingControl, SetMute, 0 Master 1
2021.04.17 12:24:23 5: UPNPController: RenderingControl: urn:schemas-upnp-org:service:RenderingControl:1 found. OK.

2021.04.17 12:24:59 5: UPNPController: UPNP_Controller, 192.168.178.61_2870, RenderingControl, SetMute, 0 Master 0
2021.04.17 12:24:59 5: UPNPController: RenderingControl: urn:schemas-upnp-org:service:RenderingControl:1 found. OK.
2021.04.17 12:25:11 5: UPNPController: UPNP_Controller, 192.168.178.61_2870, RenderingControl, SetMute, 0 Master 0
2021.04.17 12:25:11 5: UPNPController: RenderingControl: urn:schemas-upnp-org:service:RenderingControl:1 found. OK.
2021.04.17 12:25:20 4: UPNPController: try to renew subscriptions for services, device UPNP_Controller with timeout: 100
2021.04.17 12:25:25 5: UPNPController: UPNP_Controller, 192.168.178.61_2870, RenderingControl, SetMute, 0 Master 1
2021.04.17 12:25:25 5: UPNPController: RenderingControl: urn:schemas-upnp-org:service:RenderingControl:1 found. OK.


Viele Grüße
Dieter

[KölnSolar]

Hi Dieter,

ich hoffe dass bei dir nach dem Plattencrash wieder alles läuft!

Leider noch lange nicht. Ich harre erst einmal der rekonstruierten Daten vom Spezialisten.

Mich wundert an der Aussage

Am TV sehe ich das Mute-Zeichen nicht; muss aber nichts heißen

, dass Du das doch hören müsstest.  Wenn der TV das nicht anzeigt, dann ist das halt so. Lässt sich nicht von außen beeinflussen. Ich gehe jetzt mal davon aus, dass die actions funktionieren.

Die von Dir verlinkte Änderung habe ich eingebaut

Da war ich wohl mit meiner Festplatte beschäftigt. Der Vestel macht ja gar kein Probleme bzgl. IP/Port. Die Änderung bringt daher gar nichts.  Sorry.

Nun mal, was ich im Log sehe

Code Auswählen Erweitern

12:00:36 TV eingeschaltet --> present=online, services werden subscribed, so dass die readings im DLNA-device auch den aktuellen Status wiederspiegeln
12:01:52 Lautstärkeänderung aus FHEM heraus auf 46 --> TV schickt Bestätigung(hier scheint sich also zu bestätigen: FB führt nicht zu events, anders ausgelöste Befehle schon)
<Volume channel="Master" val="46"/><VolumeDB channel="Master" val="-2621"/>
12:01:58 Lautstärkeänderung aus FHEM heraus auf 0 --> TV schickt Bestätigung
<Volume channel="Master" val="0"/><VolumeDB channel="Master" val="-32768"/>
12:02:05 Lautstärkeänderung aus FHEM heraus auf 18 --> TV schickt Bestätigung
<Volume channel="Master" val="18"/><VolumeDB channel="Master" val="-20971"/>
12:02:34 Lautstärkeänderung aus FHEM heraus auf 18 --> TV schickt Bestätigung, aber es gibt ein Problem: 12:02:35 3: UPNPController: handleOnce failed, Can't use string ("
") as an ARRAY ref while "strict refs" in use at FHEM/lib/UPnP/ControlPoint.pm line 584.
Verstehe ich gerade nicht.....

Code Auswählen Erweitern

12:02:51 Lautstärkeänderung aus FHEM heraus auf 18 --> TV schickt Bestätigung, aber das selbe Problem
12:03:08 Voicemessage Hallo auf TV -> Bestätigung mit <TransportState val="STOPPED"/>(hier gibt es einen xml-Fehler;den kenn ich; das liegt an "&amp;amp;q=Hallo")
12:03:09 Status-event <TransportState val="PLAYING"/>
12:03:12 Status-event <TransportState val="STOPPED"/>
12:04:09 Voicemessage Welcome auf TV
....
12:04:34 stop-Befehl
12:04:46 5: UPNPController: renew subscription for service RenderingControl, AVTransport of device 192.168.178.61_2870
12:04:46 Lautstärkeänderung aus FHEM heraus auf 0 --> TV schickt Bestätigung
<Volume channel="Master" val="0"/><VolumeDB channel="Master" val="-32768"/>
Was mich wundert, dass das renewal zufällig gleichzeitig zum set-Befehl lief ?  kommt dahinter nichts mehr ? Ich frage mich halt, ob das renewal wider Erwarten funktioniert hat ?

Nun die mutes:

Code Auswählen Erweitern

12:22:10 TV eingeschaltet(oder FHEM rebootet ?)
12:22:47 set mute
12:22:47  TV sagt <Mute channel="Master" val="1"/>
12:23:20 Lautstärkeänderung aus FHEM heraus auf 42
12:23:20 TV sagt <Volume channel="Master" val="42"/><VolumeDB channel="Master" val="-5243"/>
12:23:21 Lautstärkeänderung aus FHEM heraus auf 16
12:23:21 TV sagt <Volume channel="Master" val="16"/><VolumeDB channel="Master" val="-22282"/>
12:23:52 set mute
12:23:52  TV sagt ....nix
12:24:23 set mute
12:24:23 TV sagt ....nix
12:24:59 set mute off
12:24:59 TV sagt ....nix
12:25:11 set mute off
12:25:11 TV sagt ....nix
12:25:25 set mute
12:25:25 TV sagt ....nix

Ob da jetzt nur it-technisch keine Antworten vom TV kamen, oder ob der Befehl vom TV "nicht zur Kenntnis" genommen wurde, kann ich natürlich nicht sagen. Mir fehlt remote die Akustik.

Ich würd dann mal so spekulieren
- online/offline funktioniert
- renewal der subscription läuft auf Fehler 412
  wird aber durch das Modul "geheilt"
- keine events v. RenderingControl(volume, mute...) wg. fehlerhafter xml-description(möglicherweise nur nicht bei FB-Befehl ?)
- events f. AVTransport(streaming)
- ausgelöste actions funktionieren mit den "Samsung-Attributen". (streaming,volume,mute)

Ich denke, wir sind dann durch, was das Modul anbelangt. Nun bliebe nur noch die Langzeitbeobachtung.

@Eki: wie sieht es bei Dir aus ? Wir könnten dann eigentlich den nächsten Schritt gehen, dass Du den UPnP-Kram aus Deinem Modul rausschmeißen könntest und wir "nur" die Schnittstellen klären müssten.

Grüße Markus

[RockFan]

Hallo Markus,

ein paar Antworten, die dir aber kaum weiterhelfen werden:

dass Du das doch hören müsstest.

Da hast du natürlich Recht. Zur Erklärung: Ich mache alle Tests ohne "Programm" - sozusagen auf einem schwarzen Bild ohne Ton -, da wir TV nur noch streamen und ich es (bisher) nicht für relevant hielt, dass ich auch was sehen/hören muss 

kommt dahinter nichts mehr ? Ich frage mich halt, ob das renewal wider Erwarten funktioniert hat ?

Es kommt etwas später schon noch was. Aber sieht nicht brauchbar aus:

Code Auswählen Erweitern


2021.04.17 12:05:13 5: UPNPController: warning: main, Use of uninitialized value $filemtime in concatenation (.) or string at ./FHEM/70_WINCONNECT.pm line 252.

2021.04.17 12:05:13 5: UPNPController: warning: main, Use of uninitialized value $filemtime in string eq at ./FHEM/70_WINCONNECT.pm line 281.

2021.04.17 12:05:13 5: UPNPController: warning: main, Use of uninitialized value $value in string eq at fhem.pl line 4879.


2021.04.17 12:05:49 4: UPNPController: try to renew subscriptions for services, device UPNP_Controller with timeout: 100
2021.04.17 12:06:11 5: UPNPController: warning: main, Use of uninitialized value $filemtime in concatenation (.) or string at ./FHEM/70_WINCONNECT.pm line 252.

2021.04.17 12:06:11 5: UPNPController: warning: main, Use of uninitialized value $filemtime in string eq at ./FHEM/70_WINCONNECT.pm line 281.

2021.04.17 12:06:11 5: UPNPController: warning: main, Use of uninitialized value $value in string eq at fhem.pl line 4879.


12:22:10 TV eingeschaltet(oder FHEM rebootet ?)

TV eingeschaltet, da ich Mute-Test vergessen hatte

Viele Grüße
Dieter

[KölnSolar]

Hi Dieter,

Es kommt etwas später schon noch was. Aber sieht nicht brauchbar aus:

Doch

2021.04.17 12:05:49 4: UPNPController: try to renew subscriptions for services, device UPNP_Controller with timeout: 100

Diese Zeile besagt als Folgezeile von

2021.04.17 12:04:46 4: UPNPController: try to renew subscriptions for services, device UPNP_Controller with timeout: 100
2021.04.17 12:04:46 5: UPNPController: renew subscription for service RenderingControl of device 192.168.178.61_2870
2021.04.17 12:04:46 5: UPNPController: renew subscription for service AVTransport of device 192.168.178.61_2870

, dass das renewal der subscription funktioniert hat, kein Fehler 412 - precondition failed dazwischen. Also funktioniert das renewal doch ? Ne Idee was Du anders gemacht hast gegenüber früheren Tests, wo 412 auftauchte ?
Grüße Markus

[RockFan]

Eigentlich nicht. Die Reihenfolge der Aktionen - ob nun von FHEM aus oder auf der FB - war sicherlich anders. Aber sonst ... fällt mir nichts ein.

Ein Unterschied ist natürlich der geänderte Code von Dir. Kann sich dadurch etwas verändert haben?

Vielleicht ist es aber auch so wie bei dem Mute-Beispiel heute. Es verhält sich nicht immer ganz gleich.

[KölnSolar]

Es verhält sich nicht immer ganz gleich

Das wäre worst case u. ist in der IT auch selten.  Irgendwas ist dann doch anders. Und wenns sowas wie die Luftfeuchtigkeit ist. 

Kann sich dadurch etwas verändert haben?

Eher nicht.
Wie gesagt

Nun bliebe nur noch die Langzeitbeobachtung