connected/disconnected events f. Router mit UPNPController (Bsp. FritzBox)

Begonnen von KölnSolar, 27 Februar 2022, 11:39:36

Vorheriges Thema - Nächstes Thema

KölnSolar

RPi3/2 buster/stretch-SamsungAV_E/N-RFXTRX-IT-RSL-NC5462-Oregon-CUL433-GT-TMBBQ-01e-CUL868-FS20-EMGZ-1W(GPIO)-DS18B20-CO2-USBRS232-USBRS422-Betty_Boop-EchoDot-OBIS(Easymeter-Q3/EMH-KW8)-PCA301(S'duino)-Deebot(mqtt2)-zigbee2mqtt

schwatter

Achso, schade...
Hatte gehofft, das das mehr Aufklärung gibt, warum es 2 x WANIPConn1gibt.

Gruß

RockFan

Hi Markus

Zitat von: KölnSolar am 06 März 2022, 09:13:05
dann mach doch bitte mal bei UPNPSocket-UPNP_Controller-40793 mit verbose 5 eine Zwangstrennung der Fritte. Irgendwas stimmt in Deiner Umgebung nicht.

Leider hatte ich den ganzen Tag keine Möglichkeit für eine Zwangstrennung.
Mittlerweile habe ich mal

usedonlyIPs      192.168.178.1

(und somit auf meine FritzBox) gesetzt. Außerdem habe ich alle alten DLNAController-Devices gelöscht.
Gerade habe ich noch verbose im UPNPController auf 5 gesetzt.

Hier mal noch ein paar teilweise bereits bekannter Logeinträge mit verbose=5

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

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

2022.03.06 22:24:34 5: UPNPController: warning: main, Use of uninitialized value $value in string eq at fhem.pl line 4962.

2022.03.06 22:24:47 5: UPNPController: try to renew subscriptions for services, device UPNP_Controller
2022.03.06 22:24:47 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 830.

2022.03.06 22:24:47 5: UPNPController: warning: main, Use of uninitialized value in concatenation (.) or string at ./FHEM/98_UPNPController.pm line 831.

2022.03.06 22:24:47 5: UPNPController: renew subscription for service RenderingControl of device 192.168.178.58_8080
2022.03.06 22:24:47 3: UPNPController: renewal of subscription for service RenderingControl of device 192.168.178.58_8080 failed: Can't call method "renew" on unblessed reference at ./FHEM/98_UPNPController.pm line 834.
, try to subscribe
2022.03.06 22:24:47 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 830.

2022.03.06 22:24:47 5: UPNPController: warning: main, Use of uninitialized value in concatenation (.) or string at ./FHEM/98_UPNPController.pm line 831.

2022.03.06 22:24:47 5: UPNPController: renew subscription for service AVTransport of device 192.168.178.58_8080
2022.03.06 22:24:47 3: UPNPController: renewal of subscription for service AVTransport of device 192.168.178.58_8080 failed: Can't call method "renew" on unblessed reference at ./FHEM/98_UPNPController.pm line 834.
, try to subscribe
2022.03.06 22:24:47 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 862.

2022.03.06 22:24:47 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 862.

2022.03.06 22:24:47 5: UPNPController: renewSubscriptionsfinished name UPNP_Controller, readingname 192.168.178.58_8080-zs-AVTransport



UPNPSocket-UPNP_Controller-40793 gibt es bei mir nicht. Ich hätte UPNPSocket-UPNP_Controller-1900 und UPNPSocket-UPNP_Controller-39537 anzubieten.

Der 1900er schreibt bei verbose 5 reichlich Folgendes ins Log:

2022.03.06 22:43:23 5: UPNPController: UPNPSocket-UPNP_Controller-1900, received message on port 1900 starting handleOnce
2022.03.06 22:43:23 4: UPNPController: UPNPSocket-UPNP_Controller-1900, received ssdp event: was checked by discoverCallback for removed or added devices against pending search requests


Deshalb muss ich für ihn erstmal seine Gesprächigkeit wieder raus nehmen.


Wenn ich es schaffe dann morgen früh die Logeinträge während der automatischen Trennung. Und vielleicht schaffe ich auch eine manuelle Trennung mit verbose 5 für den 1900er (wenn er denn der Richtige ist)

Viele Grüße
Dieter
Raspbian (Buster) auf Raspberry Pi 4 /  CUL + RFXTRX + TCM / FS20, FHT 80B, S300TH, Intertechno, DMX, Milight, EnOcean, Homematic, AMAD, Home Connect, MiSmartHome, Yeelight, ...

KölnSolar

Hi Dieter,
ZitatUPNPSocket-UPNP_Controller-40793
Die Nr. ändert sich mit jedem define,restart,attr(tw.). Hier kommen die events an.
ZitatUPNPSocket-UPNP_Controller-1900
Ist immer vorhanden. Hier wird auf die alive-messages gelauscht.

ZitatDeshalb muss ich für ihn erstmal seine Gesprächigkeit wieder raus nehmen
Das ist ok. Mit
ZitatusedonlyIPs      192.168.178.1
genügt verbose 5 beim UPNP_Controller und UPNPSocket-UPNP_Controller-xyz. Dort kommen jetzt nur noch messages von der Fritte an.

ZitatUnd vielleicht schaffe ich auch eine manuelle Trennung mit verbose 5 für den 1900er (wenn er denn der Richtige ist)
Brauchst Du nicht.

Gute Nacht
Markus
RPi3/2 buster/stretch-SamsungAV_E/N-RFXTRX-IT-RSL-NC5462-Oregon-CUL433-GT-TMBBQ-01e-CUL868-FS20-EMGZ-1W(GPIO)-DS18B20-CO2-USBRS232-USBRS422-Betty_Boop-EchoDot-OBIS(Easymeter-Q3/EMH-KW8)-PCA301(S'duino)-Deebot(mqtt2)-zigbee2mqtt

RockFan

Guten Morgen Markus,

von der Box sehe ich gar keine Logeinträge.

Folgendes kommt einige Male zum Zeitpunkt der Trennung:

2022.03.07 03:52:07 5: UPNPController: UPNPSocket-UPNP_Controller-39537, received message on port 39537 starting handleOnce
2022.03.07 03:52:07 3: UPNPController: UPNPSocket-UPNP_Controller-39537, handleOnce failed, junk '
' after XML element

2022.03.07 03:52:07 4: UPNPController: UPNPSocket-UPNP_Controller-39537, received subscription event: event was processed by subscriptionCallback , junk '
' after XML element

2022.03.07 03:52:07 5: UPNPController: UPNPSocket-UPNP_Controller-39537, received message on port 39537 starting handleOnce


und dann

2022.03.07 03:52:43 5: UPNPController: try to renew subscriptions for services, device UPNP_Controller
2022.03.07 03:52:43 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 830.

2022.03.07 03:52:43 5: UPNPController: warning: main, Use of uninitialized value in concatenation (.) or string at ./FHEM/98_UPNPController.pm line 831.

2022.03.07 03:52:43 5: UPNPController: renew subscription for service RenderingControl of device 192.168.178.58_8080
2022.03.07 03:52:43 3: UPNPController: renewal of subscription for service RenderingControl of device 192.168.178.58_8080 failed: Can't call method "renew" on unblessed reference at ./FHEM/98_UPNPController.pm line 834.
, try to subscribe
2022.03.07 03:52:43 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 830.

2022.03.07 03:52:43 5: UPNPController: warning: main, Use of uninitialized value in concatenation (.) or string at ./FHEM/98_UPNPController.pm line 831.

2022.03.07 03:52:43 5: UPNPController: renew subscription for service AVTransport of device 192.168.178.58_8080
2022.03.07 03:52:43 3: UPNPController: renewal of subscription for service AVTransport of device 192.168.178.58_8080 failed: Can't call method "renew" on unblessed reference at ./FHEM/98_UPNPController.pm line 834.
, try to subscribe
2022.03.07 03:52:44 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 862.

2022.03.07 03:52:44 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 862.

2022.03.07 03:52:44 5: UPNPController: renewSubscriptionsfinished name UPNP_Controller, readingname 192.168.178.58_8080-zs-AVTransport



Einziger Eintrag dann etwas später:

2022.03.07 05:53:34 5: UPNPController: renew subscription for service WANIPConn1 of device 192.168.178.1_49000_1-zz-zz 1800


Viele Grüße
Dieter
Raspbian (Buster) auf Raspberry Pi 4 /  CUL + RFXTRX + TCM / FS20, FHT 80B, S300TH, Intertechno, DMX, Milight, EnOcean, Homematic, AMAD, Home Connect, MiSmartHome, Yeelight, ...

KölnSolar

Guten Morgen Dieter,
ich hab keinen Plan, was da bei Dir im Argen ist.  :-\ :'(
Schon sehr überraschend, dass um 3:52 ein renewal der subscription für die ...58 ausgeführt wurde. Die Fehlermeldungen dazu sind nachvollziehbar, da das device ja gar nicht mehr berücksichtigt wird.

Diese Meldung
Zitat2022.03.06 17:14:02 5: UPNPController: try to renew subscriptions for services, device UPNP_Controller
sollte eigentlich minütlich im Log auftauchen. Wenn dem nicht so ist, dann stimmt doch da etwas mit Deinen internaltimer nicht.

Gucken wir mal wie es sich entwickelt.

Grüße Markus
RPi3/2 buster/stretch-SamsungAV_E/N-RFXTRX-IT-RSL-NC5462-Oregon-CUL433-GT-TMBBQ-01e-CUL868-FS20-EMGZ-1W(GPIO)-DS18B20-CO2-USBRS232-USBRS422-Betty_Boop-EchoDot-OBIS(Easymeter-Q3/EMH-KW8)-PCA301(S'duino)-Deebot(mqtt2)-zigbee2mqtt

RockFan

Zitat von: KölnSolar am 07 März 2022, 07:24:24
Guten Morgen Dieter,
ich hab keinen Plan, was da bei Dir im Argen ist.  :-\ :'(
Schon sehr überraschend, dass um 3:52 ein renewal der subscription für die ...58 ausgeführt wurde. Die Fehlermeldungen dazu sind nachvollziehbar, da das device ja gar nicht mehr berücksichtigt wird.

Diese Meldung sollte eigentlich minütlich im Log auftauchen. Wenn dem nicht so ist, dann stimmt doch da etwas mit Deinen internaltimer nicht.

Gucken wir mal wie es sich entwickelt.

Grüße Markus

UPNPController: try to renew subscriptions for services, device UPNP_Controller kommt minütlich. Also wenigstens das scheint ok zu sein ...  ;)
Raspbian (Buster) auf Raspberry Pi 4 /  CUL + RFXTRX + TCM / FS20, FHT 80B, S300TH, Intertechno, DMX, Milight, EnOcean, Homematic, AMAD, Home Connect, MiSmartHome, Yeelight, ...

KölnSolar

Wenn Du die Möglichkeit hast, dann poste bitte ein aktuelles list des UPNP_Controller.

Und dann doch noch einmal eine Zwangstrennung probieren.
RPi3/2 buster/stretch-SamsungAV_E/N-RFXTRX-IT-RSL-NC5462-Oregon-CUL433-GT-TMBBQ-01e-CUL868-FS20-EMGZ-1W(GPIO)-DS18B20-CO2-USBRS232-USBRS422-Betty_Boop-EchoDot-OBIS(Easymeter-Q3/EMH-KW8)-PCA301(S'duino)-Deebot(mqtt2)-zigbee2mqtt

Gisbert

Hallo Markus,

ich hab die Definition wie im Eingangs-Post durchgeführt. Ich bekomme jedoch keine Readings rein.
Hier ein list:
Internals:
   CFGFN     
   FD         83
   FUUID      6225ba3b-f33f-e986-0d52-1562382ead289f90
   NAME       Fritzbox
   NR         11310
   NTFY_ORDER 50-Fritzbox
   STATE      initialized
   TYPE       UPNPController
   UDN        0
   VERSION    v0.0.4
   READINGS:
     2022-03-07 08:55:40   state           initialized
   helper:
     bm:
       UPNPController_Attribute:
         cnt        4
         dmx        -1000
         dtot       0
         dtotcnt    0
         mTS        07.03. 08:55:39
         max        0.00223803520202637
         tot        0.00237011909484863
         mAr:
           set
           Fritzbox
           usedonlyIPs
           192.168.178.1
       UPNPController_Define:
         cnt        1
         dmx        -1000
         dtot       0
         dtotcnt    0
         mTS        07.03. 08:54:35
         max        0.0268099308013916
         tot        0.0268099308013916
         mAr:
           HASH(0x55a863f68560)
           Fritzbox UPNPController
       UPNPController_Notify:
         cnt        3158
         dmx        -1000
         dtot       0
         dtotcnt    0
         mTS        07.03. 09:01:05
         max        0.00124716758728027
         tot        0.153427362442017
         mAr:
           HASH(0x55a863f68560)
           HASH(0x55a8624346a8)
       UPNPController_Set:
         cnt        38
         dmx        -1000
         dtot       0
         dtotcnt    0
         mTS        07.03. 08:59:33
         max        0.0217931270599365
         tot        0.468376398086548
         mAr:
           HASH(0x55a863f68560)
           Fritzbox
           ?
Attributes:
   group      FRITZBOX
   room       Network
   usedonlyIPs 192.168.178.1
   userattr   acceptedUDNs defaultRoom envNamespace envPrefix ignoreUDNs
   verbose    3

Hab ich was übersehen?

Viele​ Grüße​ Gisbert​
Aktuelles FHEM | PROXMOX | Fujitsu Futro S740 | Debian 12 | UniFi | Homematic, VCCU, HMUART | ESP8266 | ATtiny85 | Wasser-, Stromzähler | tuya local | Wlan-Kamera | SIGNALduino, Flamingo Rauchmelder FA21/22RF | RHASSPY | DEYE | JK-BMS | ESPHome

KölnSolar

Hi Gisbert,
warst Du auch genügend geduldig ? Vielleicht etwas unglücklich das device Fritzbox zu nennen(was natürlich nichts am Ergebnis ändert). Im Log steht gar nichts ? Nicht bei verbose 5 ?

Ich guck mal weiter, ob mir was auffällt...

Grüße Markus


RPi3/2 buster/stretch-SamsungAV_E/N-RFXTRX-IT-RSL-NC5462-Oregon-CUL433-GT-TMBBQ-01e-CUL868-FS20-EMGZ-1W(GPIO)-DS18B20-CO2-USBRS232-USBRS422-Betty_Boop-EchoDot-OBIS(Easymeter-Q3/EMH-KW8)-PCA301(S'duino)-Deebot(mqtt2)-zigbee2mqtt

Gisbert

Hallo Markus,

ich hab das vorige Device gelöscht und ein neues angelegt; diesmal von meinem Windows PC aus.
Hier das list:
Internals:
   CFGFN     
   DEF       
   FD         72
   FUUID      6225fdf0-f33f-e986-57a8-2c74d249cd23d2fa
   NAME       FritzBox
   NR         14452
   NTFY_ORDER 50-FritzBox
   STATE      initialized
   TYPE       UPNPController
   UDN        0
   VERSION    v0.0.4
   READINGS:
     2022-03-07 13:44:29   192.168.1.5_2869-UDN uuid:916654ff-b6a9-4886-b535-4e6a0a3f222b
     2022-03-07 13:44:29   192.168.1.5_2869-friendlyName AMD-WIN10: Gisbert:
     2022-03-07 13:44:29   192.168.1.5_2869-location http://192.168.1.5:2869/upnphost/udhisapi.dll?content=uuid:916654ff-b6a9-4886-b535-4e6a0a3f222b
     2022-03-07 13:44:29   192.168.1.5_2869-manufacturer Microsoft Corporation
     2022-03-07 13:44:29   192.168.1.5_2869-modelName Windows Media Player Sharing
     2022-03-07 13:44:29   192.168.1.5_2869-modelNumber 12.0
     2022-03-07 13:44:29   192.168.1.5_2869-presence online
     2022-03-07 13:44:29   192.168.1.5_2869-zs-ConnectionManager urn:schemas-upnp-org:service:ConnectionManager:1
     2022-03-07 13:44:29   192.168.1.5_2869-zs-ContentDirectory urn:schemas-upnp-org:service:ContentDirectory:1
     2022-03-07 13:44:29   192.168.1.5_2869-zs-X_MS_MediaReceiverRegistrar urn:microsoft.com:service:X_MS_MediaReceiverRegistrar:1
     2022-03-07 13:44:53   state           initialized
   helper:
     bm:
       UPNPController_Attribute:
         cnt        6
         dmx        -1000
         dtot       0
         dtotcnt    0
         mTS        07.03. 13:44:52
         max        2.90870666503906e-05
         tot        0.00014042854309082
         mAr:
           set
           FritzBox
           usedonlyIPs
           192.168.178.1
       UPNPController_Define:
         cnt        2
         dmx        -1000
         dtot       0
         dtotcnt    0
         mTS        07.03. 13:44:51
         max        0.0825200080871582
         tot        0.109704971313477
         mAr:
           HASH(0x55a86c075f98)
           FritzBox UPNPController
       UPNPController_Notify:
         cnt        3498
         dmx        -1000
         dtot       0
         dtotcnt    0
         mTS        07.03. 13:49:37
         max        0.00776386260986328
         tot        0.181710004806519
         mAr:
           HASH(0x55a86c075f98)
           HASH(0x55a8597a2860)
       UPNPController_Read:
         cnt        40
         dmx        -1000
         dtot       0
         dtotcnt    0
         mTS        07.03. 13:45:03
         max        0.00574493408203125
         tot        0.0272507667541504
         mAr:
           HASH(0x55a86c075f98)
       UPNPController_Set:
         cnt        33
         dmx        -1000
         dtot       0
         dtotcnt    0
         mTS        07.03. 13:44:53
         max        0.020578145980835
         tot        0.390894174575806
         mAr:
           HASH(0x55a86c075f98)
           FritzBox
           ?
Attributes:
   group      FRITZBOX
   icon       it_router
   room       Network
   usedonlyIPs 192.168.178.1
   userattr   acceptedUDNs defaultRoom envNamespace envPrefix ignoreUDNs
   verbose    5

Diesmal sehe ich zumindest Readings von dem Windows-PC, allerdings nichts von der Fritzbox.
Die Fritzbox befindet sich bei mir in einem anderen Netzwerk als mei FHEM-Server (192.168.1.x), allerdings ist die Fritzbox brav von meinem PC (192.168.1.5) aus aufrufbar mit 192.168.178.1 im Browser. Kann es daran liegen, dass von der Fritzbox nichts ankommt?

Hier der log-file (Fritzbox mit kleinem b: das ist das gelöschte Device; FritzBox mit einem große B: das ist das aktuelle Device):
2022.03.07 13:43:28.651 2: UPNPController FritzBox: UPNP Controller v0.0.4 defined
2022.03.07 13:43:29.681 2: UPNPController: UPNP Controllerdevice  UPNPSocket-Fritzbox-1900 deleted but should already be deleted
2022.03.07 13:43:29.681 2: UPNPController: UPNP Controllerdevice  UPNPSocket-Fritzbox-41489 deleted but should already be deleted
2022.03.07 13:43:29.686 3: UPNPController: start searching by type with search term: ssdp:all
2022.03.07 13:44:29.133 1: PERL WARNING: Use of uninitialized value $value in string eq at fhem.pl line 4961.
2022.03.07 13:44:51.918 2: UPNPController FritzBox: UPNP Controller v0.0.4 defined
2022.03.07 13:44:51.997 3: UPNPController: destroying controlpoint of device FritzBox
2022.03.07 13:44:53.008 3: UPNPController: start searching by type with search term: ssdp:all
2022.03.07 13:44:53.084 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:44:53.085 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:44:53.085 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:44:53.086 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:44:53.087 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:44:53.087 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:44:53.088 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:44:53.088 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:44:53.089 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:44:53.089 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:44:53.090 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:44:53.090 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:44:53.091 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:44:53.091 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:44:53.092 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:44:53.093 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:44:53.093 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:44:53.094 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:44:53.094 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:44:53.095 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:44:53.096 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:44:53.096 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:44:53.097 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:44:53.097 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:44:53.098 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:44:53.098 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:44:53.099 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:44:53.099 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:44:55.078 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:44:55.079 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:44:57.053 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:44:57.054 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:45:02.851 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:45:02.852 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:45:03.025 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:45:03.026 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:45:03.457 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:45:03.459 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:45:04.096 5: UPNPController: FritzBox, received message on port 47241 starting handleOnce
2022.03.07 13:45:04.097 4: UPNPController: FritzBox, received search response on pending search request ssdp:all
2022.03.07 13:45:10.691 3: UPNPController: destroying controlpoint of device Fritzbox
2022.03.07 13:45:10.692 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at .//FHEM/98_UPNPController.pm line 1030.
2022.03.07 13:45:10.692 1: PERL WARNING: Use of uninitialized value in delete at .//FHEM/98_UPNPController.pm line 1063.
2022.03.07 13:45:10.692 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at .//FHEM/98_UPNPController.pm line 1031.
2022.03.07 13:45:10.692 5: UPNPController: Fritzbox: socket IO::Socket::INET=GLOB(0x55a868694f68) closed
2022.03.07 13:45:10.694 2: UPNPController Fritzbox: UPNP Controller undefined. All readings deleted


Viele Grüße Gisbert
Aktuelles FHEM | PROXMOX | Fujitsu Futro S740 | Debian 12 | UniFi | Homematic, VCCU, HMUART | ESP8266 | ATtiny85 | Wasser-, Stromzähler | tuya local | Wlan-Kamera | SIGNALduino, Flamingo Rauchmelder FA21/22RF | RHASSPY | DEYE | JK-BMS | ESPHome

KölnSolar

Hi Gisbert,
Zitatich hab das vorige Device gelöscht und ein neues angelegt
das ist gelogen.  ;) Das Log sagt es war die umgekehrte Reihenfolge und das ist natürlich Käse. Deshalb auch die seltsamen Logmeldungen am Anfang. Und beim Löschen ist dann sicherlich das UPNPSocket-FritzBox-1900 wieder gelöscht worden und dann geht natürlich nichts mehr.

Bitte noch einmal löschen und dann neu anlegen. Und wenn Du es Zeile für Zeile machst(erst define, später das attr setzen) kann es natürlich sein, dass bereits devices erkannt und readings angelegt wurden. Am besten daher komplett über den raw-editor anlegen, um das auszuschließen.

Grüße Markus
RPi3/2 buster/stretch-SamsungAV_E/N-RFXTRX-IT-RSL-NC5462-Oregon-CUL433-GT-TMBBQ-01e-CUL868-FS20-EMGZ-1W(GPIO)-DS18B20-CO2-USBRS232-USBRS422-Betty_Boop-EchoDot-OBIS(Easymeter-Q3/EMH-KW8)-PCA301(S'duino)-Deebot(mqtt2)-zigbee2mqtt

Gisbert

Hallo Markus,

Zitatdas ist gelogen
Du bist aber streng ;); kann sein, dass ich zuerst das 2. Device angelegt hatte, und dann das erste gelöscht habe.

Ich berichte dann demnächst, was rausgekommen ist.

Viele​ Grüße​ Gisbert​
Aktuelles FHEM | PROXMOX | Fujitsu Futro S740 | Debian 12 | UniFi | Homematic, VCCU, HMUART | ESP8266 | ATtiny85 | Wasser-, Stromzähler | tuya local | Wlan-Kamera | SIGNALduino, Flamingo Rauchmelder FA21/22RF | RHASSPY | DEYE | JK-BMS | ESPHome

RockFan

Zitat von: KölnSolar am 07 März 2022, 07:57:31
Wenn Du die Möglichkeit hast, dann poste bitte ein aktuelles list des UPNP_Controller.

Und dann doch noch einmal eine Zwangstrennung probieren.

Hi Markus,

hier wäre das List (zu Löschen, wenn Du es kopiert hast):

gelöscht


... und hier Logs während der Zwangstrennung (17:38 Uhr), die sehr mühsam zu Pasten sind, da ein Copy immer nur bis zu "handleOnce failed" möglich ist. Der unsichtbare Inhalt in den Quotes killt den Rest.


2022.03.07 17:36:35 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 862.

2022.03.07 17:36:35 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 862.

2022.03.07 17:36:35 5: UPNPController: renewSubscriptionsfinished name UPNP_Controller, readingname 192.168.178.58_8080-zs-AVTransport
2022.03.07 17:37:37 5: UPNPController: try to renew subscriptions for services, device UPNP_Controller
2022.03.07 17:37:37 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 830.

2022.03.07 17:37:37 5: UPNPController: warning: main, Use of uninitialized value in concatenation (.) or string at ./FHEM/98_UPNPController.pm line 831.

2022.03.07 17:37:37 5: UPNPController: renew subscription for service RenderingControl of device 192.168.178.58_8080
2022.03.07 17:37:37 3: UPNPController: renewal of subscription for service RenderingControl of device 192.168.178.58_8080 failed: Can't call method "renew" on unblessed reference at ./FHEM/98_UPNPController.pm line 834.
, try to subscribe
2022.03.07 17:37:37 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 830.

2022.03.07 17:37:37 5: UPNPController: warning: main, Use of uninitialized value in concatenation (.) or string at ./FHEM/98_UPNPController.pm line 831.

2022.03.07 17:37:37 5: UPNPController: renew subscription for service AVTransport of device 192.168.178.58_8080
2022.03.07 17:37:37 3: UPNPController: renewal of subscription for service AVTransport of device 192.168.178.58_8080 failed: Can't call method "renew" on unblessed reference at ./FHEM/98_UPNPController.pm line 834.
, try to subscribe
2022.03.07 17:37:38 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 862.

2022.03.07 17:37:38 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 862.

2022.03.07 17:37:38 5: UPNPController: renewSubscriptionsfinished name UPNP_Controller, readingname 192.168.178.58_8080-zs-AVTransport


Folgende Zeilen wiederholen sich sechs mal:

2022.03.07 17:38:06 5: UPNPController: UPNPSocket-UPNP_Controller-39537, received message on port 39537 starting handleOnce
2022.03.07 17:38:06 3: UPNPController: UPNPSocket-UPNP_Controller-39537, handleOnce failed, junk '
' after XML element

2022.03.07 17:38:06 4: UPNPController: UPNPSocket-UPNP_Controller-39537, received subscription event: event was processed by subscriptionCallback , junk '
' after XML element



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

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

2022.03.07 17:38:13 5: UPNPController: warning: main, Use of uninitialized value $value in string eq at fhem.pl line 4962.


... dann kommen wieder zweimal die drei Zeilen, die ich so schlecht kopieren kann. 

...und dann:

2022.03.07 17:38:40 5: UPNPController: try to renew subscriptions for services, device UPNP_Controller
2022.03.07 17:38:40 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 830.

2022.03.07 17:38:40 5: UPNPController: warning: main, Use of uninitialized value in concatenation (.) or string at ./FHEM/98_UPNPController.pm line 831.

2022.03.07 17:38:40 5: UPNPController: renew subscription for service RenderingControl of device 192.168.178.58_8080
2022.03.07 17:38:40 3: UPNPController: renewal of subscription for service RenderingControl of device 192.168.178.58_8080 failed: Can't call method "renew" on unblessed reference at ./FHEM/98_UPNPController.pm line 834.
, try to subscribe
2022.03.07 17:38:40 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 830.

2022.03.07 17:38:40 5: UPNPController: warning: main, Use of uninitialized value in concatenation (.) or string at ./FHEM/98_UPNPController.pm line 831.

2022.03.07 17:38:40 5: UPNPController: renew subscription for service AVTransport of device 192.168.178.58_8080
2022.03.07 17:38:40 3: UPNPController: renewal of subscription for service AVTransport of device 192.168.178.58_8080 failed: Can't call method "renew" on unblessed reference at ./FHEM/98_UPNPController.pm line 834.
, try to subscribe
2022.03.07 17:38:41 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 862.

2022.03.07 17:38:41 5: UPNPController: warning: main, Use of uninitialized value in subtraction (-) at ./FHEM/98_UPNPController.pm line 862.

2022.03.07 17:38:41 5: UPNPController: renewSubscriptionsfinished name UPNP_Controller, readingname 192.168.178.58_8080-zs-AVTransport


Die IP der Fritzbox kommt nicht vor, dafür aber die IP des Radios, der gar nicht beachtet werden sollte  :-\

UPNPSocket-UPNP_Controller-39537 (immer noch verbose 5) scheint auch nicht viel zu Melden zu haben  ;)

UPDATE:
Ich war neugierig: Hex "0A00" scheint den Rest der Zwischenablage zu eliminieren.

UPDATE2:
Scheint ein Problem von meinem Editor (PsPad) zu sein. Beim Notepad funktioniert das Kopieren

Viele Grüße
Dieter
Raspbian (Buster) auf Raspberry Pi 4 /  CUL + RFXTRX + TCM / FS20, FHT 80B, S300TH, Intertechno, DMX, Milight, EnOcean, Homematic, AMAD, Home Connect, MiSmartHome, Yeelight, ...

KölnSolar

kannst löschen.

Zitat2022-03-06 17:09:07   192.168.178.1_49000_1-zz-zz-zs-WANIPConn1 subscription committed, timeout: 1800
sagt, dass die subscription soweit funktioniert hatte, aber kein event folgte.  ???

Ich gucke und schreibe ggfs. weiter...

Ich mach auch mal in meinem Testsystem ein usedonlyIPs FrittenIP. Mach Du doch bitte wie Gisbert ein delete und dann define und das attr ... usedonlyIPs ... im raw-Editor.

Grüße Markus

Edit: hier stimmt was nicht....1_49000-UDN uuid:123402409-bccb-40e7-8e6c-444E6D5B9ECD fa095ecc-e13e-40e7-8e6c-444e6d5b9ecddas reading enthält 2 uuid's. Und ich hab auch nur 5 u. keine 6 devices(allerdings ne 7390).
RPi3/2 buster/stretch-SamsungAV_E/N-RFXTRX-IT-RSL-NC5462-Oregon-CUL433-GT-TMBBQ-01e-CUL868-FS20-EMGZ-1W(GPIO)-DS18B20-CO2-USBRS232-USBRS422-Betty_Boop-EchoDot-OBIS(Easymeter-Q3/EMH-KW8)-PCA301(S'duino)-Deebot(mqtt2)-zigbee2mqtt