[37_echodevice] Amazon Echo Modul (nicht Alexa)

Begonnen von michael.winkler, 12 Januar 2018, 18:20:12

Vorheriges Thema - Nächstes Thema

KölnSolar

Hi Michael,
ist das nicht das, was ich vor Monaten schon mal erwähnt hatte: die Umstellung auf keepalive nicht nur für die "Info-refreshs", sondern zusätzlich für die "Login-refreshs" ?

Wobei ich im Augenblick freezemon auf 0,4s stehen habe und echodevice nur noch ganz selten in Augenschein tritt.

Grüße Markus
Edit: Vielleicht wichtig: ich nutze kein npm-Login.
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

michael.winkler

Zitat von: KölnSolar am 16 April 2020, 13:46:55
Hi Michael,
ist das nicht das, was ich vor Monaten schon mal erwähnt hatte: die Umstellung auf keepalive nicht nur für die "Info-refreshs", sondern zusätzlich für die "Login-refreshs" ?

Wobei ich im Augenblick freezemon auf 0,4s stehen habe und echodevice nur noch ganz selten in Augenschein tritt.

Grüße Markus
Edit: Vielleicht wichtig: ich nutze kein npm-Login.
Das NPM Login wird komplett anders verarbeitet. Hier bringt ein zusätzliches keepalive überhaupt nichts.

viegener

#3932
Ich brauche offensichtlich auch mal Hilfe:

Ich habe den echo device für npm definiert:

define echo echodevice xxx@xxx.xx xxx

Dann erfolgreich die npm-Installation durchgeführt (über den echo device) - npm und node sind soweit ok aus meiner Sicht:

$ npm --version
6.13.4
$ node --version
v8.17.0


Als nächsten Schritt wollte ich NPM_login erstellen - also

set echo NPM_login new

Das friert mein FHEM dann für Minuten ein - bis ich den node-Prozess abschiesse:
/usr/bin/node ./cache/alexa-cookie/425create-cookie.js

Noch während der Prozess läuft entsteht eine Datei 425result.json
die nur den Wert
null
enthält.

Erst nach abschiessen des prozesses ist FHEM wieder erreichbar, das Browserfenster muss ich aber erst neuladen es kommt keine Folgeseite.

Achso und natürlich ist kein erfolgreicher login gesetzt und auch das reading amazon_refreshtoken steht auf "wird erzeugt"








Kein Support über PM - Anfragen gerne im Forum - Damit auch andere profitieren und helfen können

michael.winkler

Zitat von: viegener am 16 April 2020, 20:47:27
Ich brauche offensichtlich auch mal Hilfe:

Ich habe den echo device für npm definiert:

define echo echodevice xxx@xxx.xx xxx

Dann erfolgreich die npm-Installation durchgeführt (über den echo device) - npm und node sind soweit ok aus meiner Sicht:

$ npm --version
6.13.4
$ node --version
v8.17.0


Als nächsten Schritt wollte ich NPM_login erstellen - also

set echo NPM_login new

Das friert mein FHEM dann für Minuten ein - bis ich den node-Prozess abschiesse:
/usr/bin/node ./cache/alexa-cookie/425create-cookie.js

Noch während der Prozess läuft entsteht eine Datei 425result.json
die nur den Wert
null
enthält.

Erst nach abschiessen des prozesses ist FHEM wieder erreichbar, das Browserfenster muss ich aber erst neuladen es kommt keine Folgeseite.

Achso und natürlich ist kein erfolgreicher login gesetzt und auch das reading amazon_refreshtoken steht auf "wird erzeugt"
Bitte mal ein get Status vom Account device senden. Was steht im Log? Bitte das ganze auch mal mit verbose 4 versuchen, und dann log prüfen


Gesendet von iPhone mit Tapatalk

viegener

Zitat von: michael.winkler am 16 April 2020, 20:55:26
Bitte mal ein get Status vom Account device senden. Was steht im Log? Bitte das ganze auch mal mit verbose 4 versuchen, und dann log prüfen


Gesendet von iPhone mit Tapatalk

Danke erstmal für die schnelle Reaktion!

Hätte ich dazu sagen können - kein Eintrag im log im obigen Fall - ausser den Effekten des langen "freeze" und der zugehörigen freezes...

Ergebnis des "get ... status":

Modul Infos:
Beschreibung    Bereich    Wert
STATE    Reading unbekannt
Version    Reading 0.1.0
COOKIE_STATE    Reading unbekannt
COOKIE_TYPE    Reading NEW
COOKIE_MODE    Reading NPM
amazon_refreshtoken    Reading wird erzeugt
icon    Attribut echo
room    Attribut Amazon


Amazon Cookie:
Beschreibung    Bereich    Wert
.COOKIE    Reading unbekannt....
COOKIE_STATE    Reading unbekannt
COOKIE_TYPE    Reading NEW
amazon_refreshtoken    Reading wird erzeugt
.COOKIE    Helper ....
.COMMSID    Helper ....
.CSRF    Helper ....
.DIRECTID    Helper ....
RUNLOGIN    Helper 0
RUNNING_REQUEST    Helper
LOGINERROR    Helper 0



Ergebnis mit verbose 4 im log:


2020.04.16 21:57:19 4: [echo] [echodevice_LoginStart] [enable] set next internal timer start in 60 seconds.
2020.04.16 21:58:19 4: [echo] [echodevice_LoginStart] [enable] set next internal timer start in 60 seconds.
2020.04.16 21:58:26 3: [echo] [echodevice_NPMLoginNew] Node Version 8.17.0

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port Active Internet connections (servers and established)

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port Proto Recv-Q Send-Q Local Address           Foreign Address         State     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:4711            0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:46121           0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:netbios-ssn     0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:8083            0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:8084            0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:8085            0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:ssh             0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:microsoft-ds    0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:7072            0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:42593           0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0    566 pi.fritz.box:8083       NBI7.fritz.box:61156    ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:8083       NBI7.fritz.box:61969    ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:8083       NBI7.fritz.box:61975    ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:8083          localhost:60344         ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:58776      HS110.fritz.box:9999    TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:32848         localhost:7072          TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:32878         localhost:7072          TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:32828         localhost:7072          TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0  12518 pi.fritz.box:8083       NBI7.fritz.box:61148    ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:58774      HS110.fritz.box:9999    TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:57416      LaCrosseGateway.frit:81 ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:32858         localhost:7072          TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:8083       NBI7.fritz.box:61976    ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:32860         localhost:7072          TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.b:microsoft-ds NBI7.fritz.box:61134    ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:47342      ds.fritz.b:microsoft-ds ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:40684      149.154.167.220:https   ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:8083       NBI7.fritz.box:61960    TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:8083       NBI7.fritz.box:61972    TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:ssh        NBI7.fritz.box:61097    ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:57756      192.168.1.34:1000       ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:58778      HS110.fritz.box:9999    TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:4711          localhost:40626         ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:60344         localhost:8083          ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.b:microsoft-ds NBI7.fritz.box:60926    ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:32830         localhost:7072          TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:32872         localhost:7072          TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:48536      SMARTMETER.fritz:telnet ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:40626         localhost:4711          ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:33356      ec2-18-195-194-63.:8883 ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:32862         localhost:7072          TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:33438      fritz.box:1012          ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp6       0      0 [::]:netbios-ssn        [::]:*                  LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp6       0      0 [::]:ssh                [::]:*                  LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp6       0      0 [::]:3000               [::]:*                  LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp6       0      0 [::]:microsoft-ds       [::]:*                  LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp   164886      0 0.0.0.0:1900            0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 0.0.0.0:1900            0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 pi.fritz.box:35873      HS110.fritz.box:54321   ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 0.0.0.0:bootpc          0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 0.0.0.0:33414           0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 192.168.255.:netbios-ns 0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 pi.fritz.box:netbios-ns 0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 0.0.0.0:netbios-ns      0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 192.168.255:netbios-dgm 0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 pi.fritz.bo:netbios-dgm 0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 0.0.0.0:netbios-dgm     0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 0.0.0.0:49348           0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 0.0.0.0:mdns            0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp6       0      0 [::]:48935              [::]:*                             

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp6       0      0 [::]:mdns               [::]:*                             

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port raw6       0      0 [::]:ipv6-icmp          [::]:*                  7         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port Active UNIX domain sockets (servers and established)

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port Proto RefCnt Flags       Type       State         I-Node   Path

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    1556     /run/systemd/notify

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    1558     /run/systemd/cgroups-agent

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     1562     /run/systemd/private

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     SEQPACKET  LISTENING     1569     /run/udev/control

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    14612    /var/lib/samba/private/msg.sock/554

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  9      [ ]         DGRAM                    1574     /run/systemd/journal/dev-log

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    1585     /run/systemd/journal/syslog

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     1591     /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  7      [ ]         DGRAM                    1593     /run/systemd/journal/socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    13398    /var/lib/samba/private/msg.sock/552

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    10922    /var/lib/samba/private/msg.sock/558

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     1617     /run/systemd/fsck.progress

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    4569172  /run/user/999/systemd/notify

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     4569176  /run/user/999/systemd/private

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     10840    /var/run/samba/nmbd/unexpected

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    4559957  /var/lib/samba/private/msg.sock/4402

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     4569181  /run/user/999/gnupg/S.gpg-agent.ssh

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     4569184  /run/user/999/gnupg/S.gpg-agent.browser

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    10855    /var/lib/samba/private/msg.sock/553

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     4569186  /run/user/999/gnupg/S.gpg-agent

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     10338    /var/run/avahi-daemon/socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     4569188  /run/user/999/gnupg/S.gpg-agent.extra

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     10341    /run/thd.socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     10343    /var/run/dbus/system_bus_socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    10822    /var/lib/samba/private/msg.sock/548

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    4568176  /var/lib/samba/private/msg.sock/6404

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     10486    /var/run/dhcpcd.sock

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     10488    /var/run/dhcpcd.unpriv.sock

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     9812     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    4569174 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10356    /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     11424    /var/run/dbus/system_bus_socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    4569175 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     9930     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     12765    /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    4569161 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     9935     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    9928     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     8384     /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     9266     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    9268     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     4567285 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    4569158 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    4567275 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     14342   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     9931     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     4568142  /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    4043008 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     4567284 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     4569154 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    7143     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    7145     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    7146     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    7144     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    7137     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    12530   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     12532   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     9063     /var/run/dbus/system_bus_socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     7081     /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     12527   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     8983     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    1767     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10295   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10552    /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    9028     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    1768     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     12394   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     12395    /var/run/dbus/system_bus_socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    8320     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    9006     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10348    /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    9128     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     8862     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     11408   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     11409    /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     11351   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    1561     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     11411    /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10364    /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     8872     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     8871     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    1560     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    10492   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     4570130 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     8873     /var/run/dbus/system_bus_socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10350   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     11360    /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10468   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10479    /var/run/dbus/system_bus_socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     4570131 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10478   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    10470   

2020.04.16 21:58:26 3: [echo] [echodevice_NPMLoginNew] Proxy Port 3002 is free
2020.04.16 21:58:26 3: [echo] [echodevice_NPMLoginNew] Proxy IP 192.168.1.76
2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Use as Login-Amazon-URL: amazon.de

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Use as User-Agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.103 Safari/537.36

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Use as Accept-Language: de-DE

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Proxy-Mode enabled if needed: 192.168.1.76:3002 to listen on 192.168.1.76

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Step 1: get first cookie and authentication redirect

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Sending Request with {"host":"alexa.amazon.de","path":"","method":"GET","headers":{"DNT":"1","Upgrade-Insecure-Requests":"1","User-Agent":"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.103 Safari/537.36","Accept-Language":"de-DE","Connection":"keep-alive","Accept":"*/*"}}

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Response (302) - Redirect to https://www.amazon.de/ap/signin?showRmrMe=1&openid.return_to=https%3A%2F%2Falexa.amazon.de%2F&openid.identity=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.assoc_handle=amzn_dp_project_dee_de&openid.mode=checkid_setup&openid.claimed_id=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.ns=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0&

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Sending Request with {"host":"www.amazon.de","path":"/ap/signin?showRmrMe=1&openid.return_to=https%3A%2F%2Falexa.amazon.de%2F&openid.identity=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.assoc_handle=amzn_dp_project_dee_de&openid.mode=checkid_setup&openid.claimed_id=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.ns=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0&","method":"GET","headers":{"DNT":"1","Upgrade-Insecure-Requests":"1","User-Agent":"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.103 Safari/537.36","Accept-Language":"de-DE","Connection":"keep-alive","Accept":"*/*","Cookie":""},"body":""}

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Response (200)

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Add Cookie session-id = <entfernt>

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Add Cookie session-id-time = 2217787107l

2020.04.16 21:58:31 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Step 2: login empty to generate session

2020.04.16 21:58:31 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Sending Request with {"host":"www.amazon.de","path":"/ap/signin","method":"POST","headers":{"DNT":"1","Upgrade-Insecure-Requests":"1","User-Agent":"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.103 Safari/537.36","Accept-Language":"de-DE","Connection":"keep-alive","Content-Type":"application/x-www-form-urlencoded","Referer":"https://www.amazon.de/ap/signin?showRmrMe=1&openid.return_to=https%3A%2F%2Falexa.amazon.de%2F&openid.identity=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.assoc_handle=amzn_dp_project_dee_de&openid.mode=checkid_setup&openid.claimed_id=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.ns=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0&",.... <Rest entfernt>

2020.04.16 21:58:31 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Response (200)

2020.04.16 21:58:31 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Update Cookie session-id-time = 2217787111l

2020.04.16 21:58:31 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Add Cookie ubid-acbde = <entfernt>

2020.04.16 21:58:34 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Step 3: login with filled form, referer contains session id

2020.04.16 21:58:34 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Sending Request with {"host":"www.amazon.de","path":"/ap/signin","method":"POST","headers":{"DNT":"1","Upgrade-Insecure-Requests":"1","User-Agent":"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.103 Safari/537.36","Accept-Language":"de-DE","Connection":"keep-alive","Content-Type":"application/x-www-form-urlencoded","Referer":"...<Rest entfernt>

2020.04.16 21:58:34 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Response (200)

2020.04.16 21:58:34 3: [echo] [echodevice_NPMLoginNew] Result [HPM] Proxy created: !/cookie-success  -> https://alexa.amazon.com

2020.04.16 21:58:34 3: [echo] [echodevice_NPMLoginNew] Result [HPM] Proxy rewrite rule created: "^/www.amazon.com" ~> ""

2020.04.16 21:58:34 3: [echo] [echodevice_NPMLoginNew] Result [HPM] Proxy rewrite rule created: "^/alexa..amazon.com" ~> ""

2020.04.16 21:58:35 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Proxy-Server listening on port 3002

2020.04.16 21:58:35 3: [echo] [echodevice_NPMLoginNew] Result RESULT: Error: Amazon-Login-Error: Wichtige Mitteilung!: Zum besseren Schutz Ihres Kontos geben Sie bitte nochmals Ihr Passwort ein, und geben Sie dann die Zeichen ein, die in der Abbildung unten gezeigt werden. You can try to get the cookie manually by opening http://192.168.1.76:3002/ with your browser. / null


Ich habe hoffentlich alle kritischen Informationen aus dem log entfernt.

Dies ist alles bevor ich den Prozess abschiesse - danach kommen keine weiteren Einträge..



Kein Support über PM - Anfragen gerne im Forum - Damit auch andere profitieren und helfen können

michael.winkler

Zitat von: viegener am 16 April 2020, 22:31:53
Danke erstmal für die schnelle Reaktion!

Hätte ich dazu sagen können - kein Eintrag im log im obigen Fall - ausser den Effekten des langen "freeze" und der zugehörigen freezes...

Ergebnis des "get ... status":

Modul Infos:
Beschreibung    Bereich    Wert
STATE    Reading unbekannt
Version    Reading 0.1.0
COOKIE_STATE    Reading unbekannt
COOKIE_TYPE    Reading NEW
COOKIE_MODE    Reading NPM
amazon_refreshtoken    Reading wird erzeugt
icon    Attribut echo
room    Attribut Amazon


Amazon Cookie:
Beschreibung    Bereich    Wert
.COOKIE    Reading unbekannt....
COOKIE_STATE    Reading unbekannt
COOKIE_TYPE    Reading NEW
amazon_refreshtoken    Reading wird erzeugt
.COOKIE    Helper ....
.COMMSID    Helper ....
.CSRF    Helper ....
.DIRECTID    Helper ....
RUNLOGIN    Helper 0
RUNNING_REQUEST    Helper
LOGINERROR    Helper 0



Ergebnis mit verbose 4 im log:


2020.04.16 21:57:19 4: [echo] [echodevice_LoginStart] [enable] set next internal timer start in 60 seconds.
2020.04.16 21:58:19 4: [echo] [echodevice_LoginStart] [enable] set next internal timer start in 60 seconds.
2020.04.16 21:58:26 3: [echo] [echodevice_NPMLoginNew] Node Version 8.17.0

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port Active Internet connections (servers and established)

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port Proto Recv-Q Send-Q Local Address           Foreign Address         State     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:4711            0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:46121           0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:netbios-ssn     0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:8083            0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:8084            0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:8085            0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:ssh             0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:microsoft-ds    0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:7072            0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 0.0.0.0:42593           0.0.0.0:*               LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0    566 pi.fritz.box:8083       NBI7.fritz.box:61156    ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:8083       NBI7.fritz.box:61969    ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:8083       NBI7.fritz.box:61975    ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:8083          localhost:60344         ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:58776      HS110.fritz.box:9999    TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:32848         localhost:7072          TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:32878         localhost:7072          TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:32828         localhost:7072          TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0  12518 pi.fritz.box:8083       NBI7.fritz.box:61148    ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:58774      HS110.fritz.box:9999    TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:57416      LaCrosseGateway.frit:81 ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:32858         localhost:7072          TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:8083       NBI7.fritz.box:61976    ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:32860         localhost:7072          TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.b:microsoft-ds NBI7.fritz.box:61134    ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:47342      ds.fritz.b:microsoft-ds ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:40684      149.154.167.220:https   ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:8083       NBI7.fritz.box:61960    TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:8083       NBI7.fritz.box:61972    TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:ssh        NBI7.fritz.box:61097    ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:57756      192.168.1.34:1000       ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:58778      HS110.fritz.box:9999    TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:4711          localhost:40626         ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:60344         localhost:8083          ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.b:microsoft-ds NBI7.fritz.box:60926    ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:32830         localhost:7072          TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:32872         localhost:7072          TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:48536      SMARTMETER.fritz:telnet ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:40626         localhost:4711          ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:33356      ec2-18-195-194-63.:8883 ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 localhost:32862         localhost:7072          TIME_WAIT 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp        0      0 pi.fritz.box:33438      fritz.box:1012          ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp6       0      0 [::]:netbios-ssn        [::]:*                  LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp6       0      0 [::]:ssh                [::]:*                  LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp6       0      0 [::]:3000               [::]:*                  LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port tcp6       0      0 [::]:microsoft-ds       [::]:*                  LISTEN     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp   164886      0 0.0.0.0:1900            0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 0.0.0.0:1900            0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 pi.fritz.box:35873      HS110.fritz.box:54321   ESTABLISHED

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 0.0.0.0:bootpc          0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 0.0.0.0:33414           0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 192.168.255.:netbios-ns 0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 pi.fritz.box:netbios-ns 0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 0.0.0.0:netbios-ns      0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 192.168.255:netbios-dgm 0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 pi.fritz.bo:netbios-dgm 0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 0.0.0.0:netbios-dgm     0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 0.0.0.0:49348           0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp        0      0 0.0.0.0:mdns            0.0.0.0:*                         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp6       0      0 [::]:48935              [::]:*                             

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port udp6       0      0 [::]:mdns               [::]:*                             

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port raw6       0      0 [::]:ipv6-icmp          [::]:*                  7         

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port Active UNIX domain sockets (servers and established)

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port Proto RefCnt Flags       Type       State         I-Node   Path

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    1556     /run/systemd/notify

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    1558     /run/systemd/cgroups-agent

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     1562     /run/systemd/private

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     SEQPACKET  LISTENING     1569     /run/udev/control

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    14612    /var/lib/samba/private/msg.sock/554

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  9      [ ]         DGRAM                    1574     /run/systemd/journal/dev-log

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    1585     /run/systemd/journal/syslog

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     1591     /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  7      [ ]         DGRAM                    1593     /run/systemd/journal/socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    13398    /var/lib/samba/private/msg.sock/552

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    10922    /var/lib/samba/private/msg.sock/558

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     1617     /run/systemd/fsck.progress

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    4569172  /run/user/999/systemd/notify

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     4569176  /run/user/999/systemd/private

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     10840    /var/run/samba/nmbd/unexpected

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    4559957  /var/lib/samba/private/msg.sock/4402

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     4569181  /run/user/999/gnupg/S.gpg-agent.ssh

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     4569184  /run/user/999/gnupg/S.gpg-agent.browser

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    10855    /var/lib/samba/private/msg.sock/553

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     4569186  /run/user/999/gnupg/S.gpg-agent

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     10338    /var/run/avahi-daemon/socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     4569188  /run/user/999/gnupg/S.gpg-agent.extra

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     10341    /run/thd.socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     10343    /var/run/dbus/system_bus_socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    10822    /var/lib/samba/private/msg.sock/548

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    4568176  /var/lib/samba/private/msg.sock/6404

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     10486    /var/run/dhcpcd.sock

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ACC ]     STREAM     LISTENING     10488    /var/run/dhcpcd.unpriv.sock

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     9812     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    4569174 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10356    /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     11424    /var/run/dbus/system_bus_socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    4569175 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     9930     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     12765    /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    4569161 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     9935     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    9928     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     8384     /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     9266     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    9268     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     4567285 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    4569158 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    4567275 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     14342   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     9931     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     4568142  /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    4043008 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     4567284 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     4569154 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    7143     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    7145     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    7146     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    7144     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    7137     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    12530   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     12532   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     9063     /var/run/dbus/system_bus_socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     7081     /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     12527   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     8983     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    1767     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10295   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10552    /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    9028     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    1768     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     12394   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     12395    /var/run/dbus/system_bus_socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    8320     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    9006     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10348    /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    9128     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     8862     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     11408   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     11409    /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     11351   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    1561     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     11411    /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10364    /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     8872     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     8871     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         DGRAM                    1560     

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    10492   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     4570130 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     8873     /var/run/dbus/system_bus_socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10350   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     11360    /run/systemd/journal/stdout

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10468   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10479    /var/run/dbus/system_bus_socket

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     4570131 

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  3      [ ]         STREAM     CONNECTED     10478   

2020.04.16 21:58:26 4: [echo] [echodevice_NPMLoginNew] Result Proxy Port unix  2      [ ]         DGRAM                    10470   

2020.04.16 21:58:26 3: [echo] [echodevice_NPMLoginNew] Proxy Port 3002 is free
2020.04.16 21:58:26 3: [echo] [echodevice_NPMLoginNew] Proxy IP 192.168.1.76
2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Use as Login-Amazon-URL: amazon.de

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Use as User-Agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.103 Safari/537.36

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Use as Accept-Language: de-DE

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Proxy-Mode enabled if needed: 192.168.1.76:3002 to listen on 192.168.1.76

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Step 1: get first cookie and authentication redirect

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Sending Request with {"host":"alexa.amazon.de","path":"","method":"GET","headers":{"DNT":"1","Upgrade-Insecure-Requests":"1","User-Agent":"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.103 Safari/537.36","Accept-Language":"de-DE","Connection":"keep-alive","Accept":"*/*"}}

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Response (302) - Redirect to https://www.amazon.de/ap/signin?showRmrMe=1&openid.return_to=https%3A%2F%2Falexa.amazon.de%2F&openid.identity=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.assoc_handle=amzn_dp_project_dee_de&openid.mode=checkid_setup&openid.claimed_id=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.ns=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0&

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Sending Request with {"host":"www.amazon.de","path":"/ap/signin?showRmrMe=1&openid.return_to=https%3A%2F%2Falexa.amazon.de%2F&openid.identity=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.assoc_handle=amzn_dp_project_dee_de&openid.mode=checkid_setup&openid.claimed_id=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.ns=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0&","method":"GET","headers":{"DNT":"1","Upgrade-Insecure-Requests":"1","User-Agent":"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.103 Safari/537.36","Accept-Language":"de-DE","Connection":"keep-alive","Accept":"*/*","Cookie":""},"body":""}

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Response (200)

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Add Cookie session-id = <entfernt>

2020.04.16 21:58:27 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Add Cookie session-id-time = 2217787107l

2020.04.16 21:58:31 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Step 2: login empty to generate session

2020.04.16 21:58:31 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Sending Request with {"host":"www.amazon.de","path":"/ap/signin","method":"POST","headers":{"DNT":"1","Upgrade-Insecure-Requests":"1","User-Agent":"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.103 Safari/537.36","Accept-Language":"de-DE","Connection":"keep-alive","Content-Type":"application/x-www-form-urlencoded","Referer":"https://www.amazon.de/ap/signin?showRmrMe=1&openid.return_to=https%3A%2F%2Falexa.amazon.de%2F&openid.identity=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.assoc_handle=amzn_dp_project_dee_de&openid.mode=checkid_setup&openid.claimed_id=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0%2Fidentifier_select&openid.ns=http%3A%2F%2Fspecs.openid.net%2Fauth%2F2.0&",.... <Rest entfernt>

2020.04.16 21:58:31 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Response (200)

2020.04.16 21:58:31 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Update Cookie session-id-time = 2217787111l

2020.04.16 21:58:31 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Add Cookie ubid-acbde = <entfernt>

2020.04.16 21:58:34 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Step 3: login with filled form, referer contains session id

2020.04.16 21:58:34 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Sending Request with {"host":"www.amazon.de","path":"/ap/signin","method":"POST","headers":{"DNT":"1","Upgrade-Insecure-Requests":"1","User-Agent":"Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.103 Safari/537.36","Accept-Language":"de-DE","Connection":"keep-alive","Content-Type":"application/x-www-form-urlencoded","Referer":"...<Rest entfernt>

2020.04.16 21:58:34 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Response (200)

2020.04.16 21:58:34 3: [echo] [echodevice_NPMLoginNew] Result [HPM] Proxy created: !/cookie-success  -> https://alexa.amazon.com

2020.04.16 21:58:34 3: [echo] [echodevice_NPMLoginNew] Result [HPM] Proxy rewrite rule created: "^/www.amazon.com" ~> ""

2020.04.16 21:58:34 3: [echo] [echodevice_NPMLoginNew] Result [HPM] Proxy rewrite rule created: "^/alexa..amazon.com" ~> ""

2020.04.16 21:58:35 3: [echo] [echodevice_NPMLoginNew] Result Alexa-Cookie: Proxy-Server listening on port 3002

2020.04.16 21:58:35 3: [echo] [echodevice_NPMLoginNew] Result RESULT: Error: Amazon-Login-Error: Wichtige Mitteilung!: Zum besseren Schutz Ihres Kontos geben Sie bitte nochmals Ihr Passwort ein, und geben Sie dann die Zeichen ein, die in der Abbildung unten gezeigt werden. You can try to get the cookie manually by opening http://192.168.1.76:3002/ with your browser. / null


Ich habe hoffentlich alle kritischen Informationen aus dem log entfernt.

Dies ist alles bevor ich den Prozess abschiesse - danach kommen keine weiteren Einträge..
Du verwendest eine alte Version. Mach mal ein Update


Gesendet von iPhone mit Tapatalk

viegener

Zitat von: michael.winkler am 16 April 2020, 22:52:34
Du verwendest eine alte Version. Mach mal ein Update


Gesendet von iPhone mit Tapatalk

Ja sieht gut aus - mit der neuen Version (nach FHEM-update) geht es wohl - dabei war mein Stand gerade mal einen Monat alt

Danke für die schnelle Hiife!
Kein Support über PM - Anfragen gerne im Forum - Damit auch andere profitieren und helfen können

Sailor

Hallo Viegener

Zitat von: viegener am 16 April 2020, 23:06:36
Ja sieht gut aus - mit der neuen Version (nach FHEM-update) geht es wohl - dabei war mein Stand gerade mal einen Monat alt

fhem - Updates nur alle paar Monate mal?  :o

Gruss
    Sailor
******************************
Man wird immer besser...

cs-online

Hallo Michael,

ich habe den Server wie beschrieben ergänzt und mein (fast) tägliches FHEM-Update gemacht, da ist auch das Echo-Modul upgedatet worden. Nun kommen nicht mehr die Kollonnen, aber dafür ist nun eindeutig, dass immer, wenn Freezewerte um die 6s anliegen, dann ist immer diese Meldung drin und auch umgekehrt, gibt es die Meldung nur in Verbindung mit einem entsprechenden Freeze-Wert:

delay is 5.953 possibly caused by: tmr-echodevice_LoginStart(Echos)

Wie gesagt, das ist erst seit ein paar Tagen, was könnte sich da im Modul geändert haben ? Ich habe durchgehend connect und kann auch z.B. Speak absetzen.

Grüße

Christian
FHEM auf RPI 4 4GB, HM-WLAN-Gateway, einige HM-Aktoren,2x EBUSD an Heizung+Solar, ESP8266 am Strom-,Gas-,Wasserzähler, in WLAN-Steckdosen und Relaisleisten, Sonoff S20, Shelly1,2 und 2.5,Lacrosse-Gateway und Sensoren,Sduino,Alexa-Fhem,Huawei PV mit Speicher, alles auf einem RPI und da geht noch mehr

michael.winkler

Zitat von: cs-online am 17 April 2020, 08:16:46
Hallo Michael,

ich habe den Server wie beschrieben ergänzt und mein (fast) tägliches FHEM-Update gemacht, da ist auch das Echo-Modul upgedatet worden. Nun kommen nicht mehr die Kollonnen, aber dafür ist nun eindeutig, dass immer, wenn Freezewerte um die 6s anliegen, dann ist immer diese Meldung drin und auch umgekehrt, gibt es die Meldung nur in Verbindung mit einem entsprechenden Freeze-Wert:

delay is 5.953 possibly caused by: tmr-echodevice_LoginStart(Echos)

Wie gesagt, das ist erst seit ein paar Tagen, was könnte sich da im Modul geändert haben ? Ich habe durchgehend connect und kann auch z.B. Speak absetzen.

Grüße

Christian

Schick mir mal ein "get status" vom Account Device.

Kannst Du mal das Account Device auf Verbose 4 stellen und dann danach ein npm_login refresh machen? Schicke zusätzlich dann noch das LOG.

cs-online

Hallo Michael,

hier das get Status:

Modul Infos:

Beschreibung    Bereich    Wert
STATE    Reading connected
Version    Reading 0.1.3
COOKIE_STATE    Reading OK
COOKIE_TYPE    Reading NPM_Login
COOKIE_MODE    Reading NPM
amazon_refreshtoken    Reading vorhanden
disable    Attribut 0
browser_save_data    Attribut 1
room    Attribut Schnittstellen
intervalsettings    Attribut 300
browser_language    Attribut de-DE
icon    Attribut echo
server    Attribut layla.amazon.de
speak_volume    Attribut 50
verbose    Attribut 1

Amazon Cookie:

Beschreibung    Bereich    Wert
.COOKIE    Reading {"loginCookie":"frc=....
COOKIE_STATE    Reading OK
COOKIE_TYPE    Reading NPM_Login
amazon_refreshtoken    Reading vorhanden
.COOKIE    Helper session-id=261-31422....
.COMMSID    Helper amzn1.comms.id.perso....
.CSRF    Helper -13....
.DIRECTID    Helper amzn1.account.AH2H5E....
RUNLOGIN    Helper 0
RUNNING_REQUEST    Helper 0
LOGINERROR    Helper 0


und hier das Log vom NPM_Login refresh:

2020.04.17 20:52:40 4: [Echos] [echodevice_LoginStart] Alter COOKIE=1440/6000
2020.04.17 20:52:40 4: [Echos] [echodevice_LoginStart] [enable] set next internal timer start in 60 seconds.
2020.04.17 20:52:41 4: [Echos] [echodevice_NPMWaitForCookie] [NPM Login Refresh Fri Apr 17 20:52:34 2020] wait for refreshtoken exist 612refresh-cookie.js = true
2020.04.17 20:52:42 4: [Echos] [echodevice_NPMWaitForCookie] [NPM Login Refresh Fri Apr 17 20:52:34 2020] wait for refreshtoken exist 612refresh-cookie.js = true
2020.04.17 20:52:43 4: [Echos] [echodevice_NPMWaitForCookie] [NPM Login Refresh Fri Apr 17 20:52:34 2020] wait for refreshtoken exist 612refresh-cookie.js = true
2020.04.17 20:52:44 4: [Echos] [echodevice_NPMWaitForCookie] [NPM Login Refresh Fri Apr 17 20:52:34 2020] wait for refreshtoken exist 612refresh-cookie.js = true
2020.04.17 20:52:45 3: [Echos] [echodevice_NPMWaitForCookie] [NPM Login Refresh Fri Apr 17 20:52:34 2020] write new refreshtoken


hift dir das ?

Grüße Christian
FHEM auf RPI 4 4GB, HM-WLAN-Gateway, einige HM-Aktoren,2x EBUSD an Heizung+Solar, ESP8266 am Strom-,Gas-,Wasserzähler, in WLAN-Steckdosen und Relaisleisten, Sonoff S20, Shelly1,2 und 2.5,Lacrosse-Gateway und Sensoren,Sduino,Alexa-Fhem,Huawei PV mit Speicher, alles auf einem RPI und da geht noch mehr

michael.winkler

Zitat von: cs-online am 17 April 2020, 20:55:41
2020.04.17 20:52:40 4: [Echos] [echodevice_LoginStart] Alter COOKIE=1440/6000
2020.04.17 20:52:40 4: [Echos] [echodevice_LoginStart] [enable] set next internal timer start in 60 seconds.
2020.04.17 20:52:41 4: [Echos] [echodevice_NPMWaitForCookie] [NPM Login Refresh Fri Apr 17 20:52:34 2020] wait for refreshtoken exist 612refresh-cookie.js = true
2020.04.17 20:52:42 4: [Echos] [echodevice_NPMWaitForCookie] [NPM Login Refresh Fri Apr 17 20:52:34 2020] wait for refreshtoken exist 612refresh-cookie.js = true
2020.04.17 20:52:43 4: [Echos] [echodevice_NPMWaitForCookie] [NPM Login Refresh Fri Apr 17 20:52:34 2020] wait for refreshtoken exist 612refresh-cookie.js = true
2020.04.17 20:52:44 4: [Echos] [echodevice_NPMWaitForCookie] [NPM Login Refresh Fri Apr 17 20:52:34 2020] wait for refreshtoken exist 612refresh-cookie.js = true
2020.04.17 20:52:45 3: [Echos] [echodevice_NPMWaitForCookie] [NPM Login Refresh Fri Apr 17 20:52:34 2020] write new refreshtoken

Grüße Christian

Das Log sieht gut aus.

2020.04.17 20:52:40 4: [Echos] [echodevice_LoginStart] [enable] set next internal timer start in 60 seconds.
2020.04.17 20:52:41 4: [Echos] [echodevice_NPMWaitForCookie] [NPM Login Refresh Fri Apr 17 20:52:34 2020] wait for refreshtoken exist 612refresh-cookie.js = true

Zwischen der ersten Zeile liegt genau eine Sekunde. Der Timer, welcher auf das Cookie wartet, wird mit einer Verzögerung von einer Sekunde gestartet. Was aber nicht bedeutet, dass das Modul hier einen freeze hat!

Interessant wäre das Log wenn dann auch ein Freeze vorliegt.

cs-online

#3942
Hallo Michael,

hier ein Teil des Logs von heute, immer wenn Freeze-Werte im Bereich um 5 Sekunden vorliegen, ist das Echo-Modul mit tmr-echodevice_LoginStart(Echos) drin, die Werte um 5 Sekunden gibt es auch nur in Verbindung mit dem LoginStart:

2020.04.19 00:01:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 00:01:44.022 ---------------------
2020.04.19 00:01:44 1: [Freezemon] myfreezemon: possible freeze starting at 00:01:43, delay is 1.022 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer) tmr-PRESENCE_StartLocalScan(Sommerpool_Ping)
2020.04.19 00:01:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 00:01:44.067 after 0.044482 --------
2020.04.19 00:12:13 0: Strange call for nonexistent : ReadFn
2020.04.19 00:21:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 00:21:44.003 ---------------------
2020.04.19 00:21:44 5: [Freezemon] myfreezemon found something that's not a REF HMUARTLGW_CheckCredits 
2020.04.19 00:21:44 1: [Freezemon] myfreezemon: possible freeze starting at 00:21:43, delay is 1.003 possibly caused by: tmr-ESPEasy_statusRequest(ESPEasy_Kellerzaehler_Aussenwasserzaehler) tmr-HMUARTLGW_CheckCredits(HM_Gateway) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer) tmr-ESPEasy_statusRequest(ESPEasy_Schuppen_Relais_8)
2020.04.19 00:21:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 00:21:44.016 after 0.013235 --------
2020.04.19 00:22:13 0: Strange call for nonexistent : ReadFn
2020.04.19 00:27:13 0: Strange call for nonexistent : ReadFn
2020.04.19 00:47:13 0: Strange call for nonexistent : ReadFn
2020.04.19 00:56:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 00:56:44.075 ---------------------
2020.04.19 00:56:44 1: [Freezemon] myfreezemon: possible freeze starting at 00:56:43, delay is 1.075 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 00:56:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 00:56:44.089 after 0.014621 --------
2020.04.19 01:01:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 01:01:44.101 ---------------------
2020.04.19 01:01:44 1: [Freezemon] myfreezemon: possible freeze starting at 01:01:43, delay is 1.101 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 01:01:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 01:01:44.114 after 0.012627 --------
2020.04.19 01:06:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 01:06:44.042 ---------------------
2020.04.19 01:06:44 1: [Freezemon] myfreezemon: possible freeze starting at 01:06:43, delay is 1.042 possibly caused by: tmr-PRESENCE_StartLocalScan(Tanja) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-PRESENCE_StartLocalScan(Relais_Keller) tmr-at_Exec(Feuermelder_Timer) tmr-ESPEasy_statusRequest(ESPEasy_WLAN_Dose_6_PUMP)
2020.04.19 01:06:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 01:06:44.056 after 0.013972 --------
2020.04.19 01:07:13 0: Strange call for nonexistent : ReadFn
2020.04.19 01:12:13 0: Strange call for nonexistent : ReadFn
2020.04.19 01:16:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 01:16:44.105 ---------------------
2020.04.19 01:16:44 1: [Freezemon] myfreezemon: possible freeze starting at 01:16:43, delay is 1.105 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-PRESENCE_StartLocalScan(Relais_Keller) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 01:16:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 01:16:44.127 after 0.022579 --------
2020.04.19 01:17:13 0: Strange call for nonexistent : ReadFn
2020.04.19 01:22:13 0: Strange call for nonexistent : ReadFn
2020.04.19 01:31:30 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 01:31:30.213 ---------------------
2020.04.19 01:31:30 1: [Freezemon] myfreezemon: possible freeze starting at 01:31:25, delay is 5.212 possibly caused by: tmr-echodevice_LoginStart(Echos) tmr-ENIGMA2_GetStatus(Dreambox_Schlafzimmer) tmr-SYSMON_Update(Sysmon) tmr-PRESENCE_StartLocalScan(Strom_Gateways_Ping)
2020.04.19 01:31:30 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 01:31:30.254 after 0.041474 --------
2020.04.19 01:42:13 0: Strange call for nonexistent : ReadFn
2020.04.19 01:51:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 01:51:44.013 ---------------------
2020.04.19 01:51:44 1: [Freezemon] myfreezemon: possible freeze starting at 01:51:43, delay is 1.013 possibly caused by: tmr-PRESENCE_StartLocalScan(Strom_Gateways_Ping) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 01:51:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 01:51:44.028 after 0.014495 --------
2020.04.19 01:52:13 0: Strange call for nonexistent : ReadFn
2020.04.19 02:06:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 02:06:44.015 ---------------------
2020.04.19 02:06:44 1: [Freezemon] myfreezemon: possible freeze starting at 02:06:43, delay is 1.015 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-PRESENCE_StartLocalScan(Relais_Keller) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 02:06:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 02:06:44.029 after 0.014322 --------
2020.04.19 02:12:14 0: Strange call for nonexistent : ReadFn
2020.04.19 02:26:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 02:26:44.052 ---------------------
2020.04.19 02:26:44 1: [Freezemon] myfreezemon: possible freeze starting at 02:26:43, delay is 1.052 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-PRESENCE_StartLocalScan(Relais_Keller) tmr-PRESENCE_StartLocalScan(Sommerpool_Ping) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 02:26:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 02:26:44.072 after 0.019714 --------
2020.04.19 02:31:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 02:31:44.180 ---------------------
2020.04.19 02:31:44 1: [Freezemon] myfreezemon: possible freeze starting at 02:31:43, delay is 1.179 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer) tmr-PRESENCE_StartLocalScan(Relais_Keller) tmr-PRESENCE_StartLocalScan(Sommerpool_Ping) tmr-ESPEasy_statusRequest(ESPEasy_WLAN_Dose_10_PUMP)
2020.04.19 02:31:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 02:31:44.214 after 0.034101 --------
2020.04.19 02:36:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 02:36:44.058 ---------------------
2020.04.19 02:36:44 1: [Freezemon] myfreezemon: possible freeze starting at 02:36:43, delay is 1.058 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 02:36:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 02:36:44.069 after 0.011674 --------
2020.04.19 02:37:13 0: Strange call for nonexistent : ReadFn
2020.04.19 02:41:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 02:41:44.049 ---------------------
2020.04.19 02:41:44 1: [Freezemon] myfreezemon: possible freeze starting at 02:41:43, delay is 1.048 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 02:41:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 02:41:44.061 after 0.012942 --------
2020.04.19 02:51:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 02:51:44.287 ---------------------
2020.04.19 02:51:44 1: [Freezemon] myfreezemon: possible freeze starting at 02:51:43, delay is 1.287 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 02:51:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 02:51:44.301 after 0.014128 --------
2020.04.19 02:56:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 02:56:44.094 ---------------------
2020.04.19 02:56:44 1: [Freezemon] myfreezemon: possible freeze starting at 02:56:43, delay is 1.094 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 02:56:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 02:56:44.105 after 0.011884 --------
2020.04.19 03:01:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 03:01:44.226 ---------------------
2020.04.19 03:01:44 1: [Freezemon] myfreezemon: possible freeze starting at 03:01:43, delay is 1.226 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 03:01:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 03:01:44.238 after 0.012773 --------
2020.04.19 03:06:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 03:06:44.152 ---------------------
2020.04.19 03:06:44 1: [Freezemon] myfreezemon: possible freeze starting at 03:06:43, delay is 1.152 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 03:06:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 03:06:44.164 after 0.012239 --------
2020.04.19 03:11:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 03:11:44.112 ---------------------
2020.04.19 03:11:44 1: [Freezemon] myfreezemon: possible freeze starting at 03:11:43, delay is 1.112 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 03:11:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 03:11:44.125 after 0.013195 --------
2020.04.19 03:12:36 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 03:12:36.375 ---------------------
2020.04.19 03:12:36 1: [Freezemon] myfreezemon: possible freeze starting at 03:12:31, delay is 5.374 possibly caused by: tmr-echodevice_LoginStart(Echos) tmr-PRESENCE_StartLocalScan(Tanja) tmr-ENIGMA2_GetStatus(Dreambox_Schlafzimmer) tmr-SYSMON_Update(Sysmon)
2020.04.19 03:12:36 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 03:12:36.416 after 0.041398 --------
2020.04.19 03:16:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 03:16:44.085 ---------------------
2020.04.19 03:16:44 1: [Freezemon] myfreezemon: possible freeze starting at 03:16:43, delay is 1.085 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 03:16:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 03:16:44.097 after 0.012368 --------
2020.04.19 03:21:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 03:21:44.086 ---------------------
2020.04.19 03:21:44 1: [Freezemon] myfreezemon: possible freeze starting at 03:21:43, delay is 1.085 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 03:21:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 03:21:44.099 after 0.013886 --------
2020.04.19 03:26:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 03:26:44.001 ---------------------
2020.04.19 03:26:44 1: [Freezemon] myfreezemon: possible freeze starting at 03:26:43, delay is 1.001 possibly caused by: tmr-ESPEasy_statusRequest(ESPEasy_WLAN_Dose_3_PUMP) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 03:26:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 03:26:44.016 after 0.015144 --------
2020.04.19 03:31:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 03:31:44.086 ---------------------
2020.04.19 03:31:44 1: [Freezemon] myfreezemon: possible freeze starting at 03:31:43, delay is 1.086 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 03:31:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 03:31:44.101 after 0.014982 --------
2020.04.19 03:36:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 03:36:44.212 ---------------------
2020.04.19 03:36:44 1: [Freezemon] myfreezemon: possible freeze starting at 03:36:43, delay is 1.211 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer) tmr-PRESENCE_StartLocalScan(Sommerpool_Ping)
2020.04.19 03:36:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 03:36:44.258 after 0.046544 --------
2020.04.19 03:37:14 0: Strange call for nonexistent : ReadFn
2020.04.19 03:41:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 03:41:44.228 ---------------------
2020.04.19 03:41:44 5: [Freezemon] myfreezemon found something that's not a REF HMUARTLGW_CheckCredits 
2020.04.19 03:41:44 1: [Freezemon] myfreezemon: possible freeze starting at 03:41:43, delay is 1.228 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer) tmr-HMUARTLGW_CheckCredits(HM_Gateway)
2020.04.19 03:41:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 03:41:44.241 after 0.013354 --------
2020.04.19 03:42:14 0: Strange call for nonexistent : ReadFn
2020.04.19 03:46:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 03:46:44.196 ---------------------
2020.04.19 03:46:44 1: [Freezemon] myfreezemon: possible freeze starting at 03:46:43, delay is 1.196 possibly caused by: tmr-DbLog_execmemcache(LOGDB) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer) tmr-PRESENCE_StartLocalScan(Relais_Keller)
2020.04.19 03:46:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 03:46:44.239 after 0.043044 --------
2020.04.19 04:01:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 04:01:44.248 ---------------------
2020.04.19 04:01:44 1: [Freezemon] myfreezemon: possible freeze starting at 04:01:43, delay is 1.247 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer) tmr-PRESENCE_StartLocalScan(Relais_Keller) tmr-PRESENCE_StartLocalScan(Sommerpool_Ping)
2020.04.19 04:01:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 04:01:44.292 after 0.044454 --------
2020.04.19 04:02:14 0: Strange call for nonexistent : ReadFn
2020.04.19 04:07:14 0: Strange call for nonexistent : ReadFn
2020.04.19 04:11:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 04:11:44.090 ---------------------
2020.04.19 04:11:44 1: [Freezemon] myfreezemon: possible freeze starting at 04:11:43, delay is 1.09 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer) tmr-PRESENCE_StartLocalScan(Sommerpool_Ping)
2020.04.19 04:11:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 04:11:44.133 after 0.042777 --------
2020.04.19 04:17:14 0: Strange call for nonexistent : ReadFn
2020.04.19 04:21:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 04:21:44.007 ---------------------
2020.04.19 04:21:44 1: [Freezemon] myfreezemon: possible freeze starting at 04:21:43, delay is 1.006 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer) tmr-PRESENCE_StartLocalScan(Relais_Keller)
2020.04.19 04:21:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 04:21:44.058 after 0.050667 --------
2020.04.19 04:26:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 04:26:44.036 ---------------------
2020.04.19 04:26:44 1: [Freezemon] myfreezemon: possible freeze starting at 04:26:43, delay is 1.036 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 04:26:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 04:26:44.051 after 0.014734 --------
2020.04.19 04:41:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 04:41:44.193 ---------------------
2020.04.19 04:41:44 1: [Freezemon] myfreezemon: possible freeze starting at 04:41:43, delay is 1.192 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-PRESENCE_StartLocalScan(Sommerpool_Ping) tmr-PRESENCE_StartLocalScan(Relais_Keller) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 04:41:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 04:41:44.214 after 0.0216 --------
2020.04.19 04:42:14 0: Strange call for nonexistent : ReadFn
2020.04.19 04:46:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 04:46:44.049 ---------------------
2020.04.19 04:46:44 1: [Freezemon] myfreezemon: possible freeze starting at 04:46:43, delay is 1.048 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer) tmr-PRESENCE_StartLocalScan(Sommerpool_Ping)
2020.04.19 04:46:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 04:46:44.096 after 0.047282 --------
2020.04.19 04:47:14 0: Strange call for nonexistent : ReadFn
2020.04.19 04:52:14 0: Strange call for nonexistent : ReadFn
2020.04.19 04:53:42 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 04:53:42.979 ---------------------
2020.04.19 04:53:42 1: [Freezemon] myfreezemon: possible freeze starting at 04:53:37, delay is 5.979 possibly caused by: tmr-at_Exec(Stromwerte_Timer) tmr-at_Exec(Stromwerte_Timer2) tmr-echodevice_LoginStart(Echos) tmr-Shelly_status(Rolladen_Buero) tmr-Shelly_status(Leinwand_Wohnzimmer) tmr-ENIGMA2_GetStatus(Dreambox_Schlafzimmer) tmr-SYSMON_Update(Sysmon)
2020.04.19 04:53:43 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 04:53:43.023 after 0.044064 --------
2020.04.19 05:02:14 0: Strange call for nonexistent : ReadFn
2020.04.19 05:06:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 05:06:44.148 ---------------------
2020.04.19 05:06:44 1: [Freezemon] myfreezemon: possible freeze starting at 05:06:43, delay is 1.148 possibly caused by: tmr-echodevice_LoginStart(Echos) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 05:06:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 05:06:44.163 after 0.014644 --------
2020.04.19 05:07:14 0: Strange call for nonexistent : ReadFn
2020.04.19 05:21:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 05:21:44.090 ---------------------
2020.04.19 05:21:44 1: [Freezemon] myfreezemon: possible freeze starting at 05:21:43, delay is 1.09 possibly caused by: tmr-echodevice_LoginStart(Echos) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 05:21:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 05:21:44.104 after 0.013634 --------
2020.04.19 05:31:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 05:31:44.088 ---------------------
2020.04.19 05:31:44 1: [Freezemon] myfreezemon: possible freeze starting at 05:31:43, delay is 1.087 possibly caused by: tmr-echodevice_LoginStart(Echos) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer) tmr-PRESENCE_StartLocalScan(Sommerpool_Ping)
2020.04.19 05:31:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 05:31:44.135 after 0.047605 --------
2020.04.19 05:32:14 0: Strange call for nonexistent : ReadFn
2020.04.19 05:41:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 05:41:44.104 ---------------------
2020.04.19 05:41:44 1: [Freezemon] myfreezemon: possible freeze starting at 05:41:43, delay is 1.104 possibly caused by: tmr-PRESENCE_StartLocalScan(Sommerpool_Ping) tmr-at_Exec(Heiztimer) tmr-echodevice_LoginStart(Echos) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer) tmr-PRESENCE_StartLocalScan(Relais_Keller) tmr-PRESENCE_StartLocalScan(Whirlpool_Ping)
2020.04.19 05:41:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 05:41:44.152 after 0.047843 --------
2020.04.19 05:42:14 0: Strange call for nonexistent : ReadFn
2020.04.19 06:02:14 0: Strange call for nonexistent : ReadFn
2020.04.19 06:06:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 06:06:44.022 ---------------------
2020.04.19 06:06:44 1: [Freezemon] myfreezemon: possible freeze starting at 06:06:43, delay is 1.021 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 06:06:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 06:06:44.035 after 0.013174 --------
2020.04.19 06:16:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 06:16:44.035 ---------------------
2020.04.19 06:16:44 1: [Freezemon] myfreezemon: possible freeze starting at 06:16:43, delay is 1.035 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-PRESENCE_StartLocalScan(Relais_Keller) tmr-at_Exec(Feuermelder_Timer) tmr-PRESENCE_StartLocalScan(Whirlpool_Ping) tmr-PRESENCE_StartLocalScan(Gewaechshaus_Ping)
2020.04.19 06:16:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 06:16:44.081 after 0.046069 --------
2020.04.19 06:21:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 06:21:44.055 ---------------------
2020.04.19 06:21:44 1: [Freezemon] myfreezemon: possible freeze starting at 06:21:43, delay is 1.055 possibly caused by: tmr-ESPEasy_statusRequest(ESPEasy_Kellerzaehler_Aussenwasserzaehler) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-ESPEasy_statusRequest(ESPEasy_Kellerzaehler_Gaszaehler) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 06:21:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 06:21:44.069 after 0.014245 --------
2020.04.19 06:31:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 06:31:44.110 ---------------------
2020.04.19 06:31:44 1: [Freezemon] myfreezemon: possible freeze starting at 06:31:43, delay is 1.11 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 06:31:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 06:31:44.124 after 0.01405 --------
2020.04.19 06:34:50 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 06:34:50.514 ---------------------
2020.04.19 06:34:50 1: [Freezemon] myfreezemon: possible freeze starting at 06:34:45, delay is 5.513 possibly caused by: tmr-echodevice_LoginStart(Echos) tmr-ENIGMA2_GetStatus(Dreambox_Schlafzimmer) tmr-SYSMON_Update(Sysmon)
2020.04.19 06:34:50 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 06:34:50.555 after 0.041163 --------
2020.04.19 06:41:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 06:41:44.007 ---------------------
2020.04.19 06:41:44 1: [Freezemon] myfreezemon: possible freeze starting at 06:41:43, delay is 1.007 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 06:41:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 06:41:44.019 after 0.012016 --------
2020.04.19 06:51:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 06:51:44.010 ---------------------
2020.04.19 06:51:44 1: [Freezemon] myfreezemon: possible freeze starting at 06:51:43, delay is 1.01 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 06:51:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 06:51:44.023 after 0.013095 --------
2020.04.19 06:52:14 0: Strange call for nonexistent : ReadFn
2020.04.19 07:01:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 07:01:44.064 ---------------------
2020.04.19 07:01:44 5: [Freezemon] myfreezemon found something that's not a REF HMUARTLGW_CheckCredits 
2020.04.19 07:01:44 1: [Freezemon] myfreezemon: possible freeze starting at 07:01:43, delay is 1.063 possibly caused by: tmr-HMUARTLGW_CheckCredits(HM_Gateway) tmr-ESPEasy_statusRequest(ESPEasy_Schuppen_Relais_6) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer) tmr-PRESENCE_StartLocalScan(Relais_Keller) tmr-PRESENCE_StartLocalScan(Whirlpool_Ping)
2020.04.19 07:01:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 07:01:44.114 after 0.050527 --------
2020.04.19 07:11:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 07:11:44.005 ---------------------
2020.04.19 07:11:44 1: [Freezemon] myfreezemon: possible freeze starting at 07:11:43, delay is 1.004 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-PRESENCE_StartLocalScan(Gewaechshaus_Ping) tmr-PRESENCE_StartLocalScan(Whirlpool_Ping) tmr-PRESENCE_StartLocalScan(Relais_Keller) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 07:11:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 07:11:44.025 after 0.021057 --------
2020.04.19 07:21:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 07:21:44.240 ---------------------
2020.04.19 07:21:44 1: [Freezemon] myfreezemon: possible freeze starting at 07:21:43, delay is 1.239 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-PRESENCE_StartLocalScan(Whirlpool_Ping) tmr-PRESENCE_StartLocalScan(Relais_Keller) tmr-at_Exec(Feuermelder_Timer) tmr-PRESENCE_StartLocalScan(Gewaechshaus_Ping)
2020.04.19 07:21:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 07:21:44.286 after 0.046409 --------
2020.04.19 07:27:14 0: Strange call for nonexistent : ReadFn
2020.04.19 07:31:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 07:31:44.010 ---------------------
2020.04.19 07:31:44 1: [Freezemon] myfreezemon: possible freeze starting at 07:31:43, delay is 1.01 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 07:31:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 07:31:44.023 after 0.012744 --------
2020.04.19 07:32:14 0: Strange call for nonexistent : ReadFn
2020.04.19 07:41:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 07:41:44.074 ---------------------
2020.04.19 07:41:44 1: [Freezemon] myfreezemon: possible freeze starting at 07:41:43, delay is 1.073 possibly caused by: tmr-ESPEasy_statusRequest(ESPEasy_Schuppen_Uptime) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 07:41:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 07:41:44.086 after 0.012973 --------
2020.04.19 07:47:14 0: Strange call for nonexistent : ReadFn
2020.04.19 07:51:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 07:51:44.041 ---------------------
2020.04.19 07:51:44 1: [Freezemon] myfreezemon: possible freeze starting at 07:51:43, delay is 1.041 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-PRESENCE_StartLocalScan(Relais_Keller) tmr-PRESENCE_StartLocalScan(Whirlpool_Ping) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 07:51:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 07:51:44.061 after 0.019815 --------
2020.04.19 07:52:15 0: Strange call for nonexistent : ReadFn
2020.04.19 07:56:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 07:56:44.010 ---------------------
2020.04.19 07:56:44 1: [Freezemon] myfreezemon: possible freeze starting at 07:56:43, delay is 1.01 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 07:56:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 07:56:44.023 after 0.013104 --------
2020.04.19 08:01:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 08:01:44.067 ---------------------
2020.04.19 08:01:44 1: [Freezemon] myfreezemon: possible freeze starting at 08:01:43, delay is 1.067 possibly caused by: tmr-PRESENCE_StartLocalScan(Sommerpool_Ping) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-PRESENCE_StartLocalScan(Relais_Keller) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 08:01:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 08:01:44.095 after 0.028582 --------
2020.04.19 08:11:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 08:11:44.014 ---------------------
2020.04.19 08:11:44 1: [Freezemon] myfreezemon: possible freeze starting at 08:11:43, delay is 1.014 possibly caused by: tmr-PRESENCE_StartLocalScan(Tanja) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 08:11:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 08:11:44.028 after 0.013484 --------
2020.04.19 08:15:57 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 08:15:57.396 ---------------------
2020.04.19 08:15:57 1: [Freezemon] myfreezemon: possible freeze starting at 08:15:52, delay is 5.395 possibly caused by: tmr-echodevice_LoginStart(Echos) tmr-ENIGMA2_GetStatus(Dreambox_Schlafzimmer) tmr-SYSMON_Update(Sysmon) tmr-ESPEasy_statusRequest(ESPEasy_Schuppen_Garage_offen)
2020.04.19 08:15:57 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 08:15:57.417 after 0.021121 --------
2020.04.19 08:22:15 0: Strange call for nonexistent : ReadFn
2020.04.19 08:37:15 0: Strange call for nonexistent : ReadFn
2020.04.19 09:11:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 09:11:44.085 ---------------------
2020.04.19 09:11:44 1: [Freezemon] myfreezemon: possible freeze starting at 09:11:43, delay is 1.084 possibly caused by: tmr-CODE(0x5bf9508)(__ANON__) tmr-ESPEasy_statusRequest(ESPEasy_Whirlpool_Terrasse_Temperatur_Pool) tmr-ESPEasy_statusRequest(ESPEasy_Schuppen_Garage_geschlossen) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-Shelly_status(Dimmer_WZ) tmr-PRESENCE_StartLocalScan(Whirlpool_Ping) tmr-at_Exec(Feuermelder_Timer) tmr-PRESENCE_StartLocalScan(Relais_Keller)
2020.04.19 09:11:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 09:11:44.129 after 0.044794 --------
2020.04.19 09:12:15 0: Strange call for nonexistent : ReadFn
2020.04.19 09:21:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 09:21:44.070 ---------------------
2020.04.19 09:21:44 1: [Freezemon] myfreezemon: possible freeze starting at 09:21:43, delay is 1.069 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 09:21:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 09:21:44.083 after 0.013149 --------
2020.04.19 09:31:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 09:31:44.333 ---------------------
2020.04.19 09:31:44 1: [Freezemon] myfreezemon: possible freeze starting at 09:31:43, delay is 1.333 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-ESPEasy_statusRequest(Temperatur_Schuppen) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 09:31:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 09:31:44.347 after 0.014576 --------
2020.04.19 09:32:15 0: Strange call for nonexistent : ReadFn
2020.04.19 09:36:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 09:36:44.108 ---------------------
2020.04.19 09:36:44 1: [Freezemon] myfreezemon: possible freeze starting at 09:36:43, delay is 1.107 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer) tmr-ESPEasy_statusRequest(ESPEasy_Schuppen_Garage_offen)
2020.04.19 09:36:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 09:36:44.119 after 0.01172 --------
2020.04.19 09:41:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 09:41:44.247 ---------------------
2020.04.19 09:41:44 1: [Freezemon] myfreezemon: possible freeze starting at 09:41:43, delay is 1.247 possibly caused by: tmr-ESPEasy_statusRequest(ESPEasy_Schuppen_Relais_3) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 09:41:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 09:41:44.261 after 0.014062 --------
2020.04.19 09:42:15 0: Strange call for nonexistent : ReadFn
2020.04.19 09:46:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 09:46:44.289 ---------------------
2020.04.19 09:46:44 1: [Freezemon] myfreezemon: possible freeze starting at 09:46:43, delay is 1.289 possibly caused by: tmr-PRESENCE_StartLocalScan(Tanja) tmr-ESPEasy_statusRequest(ESPEasy_Kellerzaehler_Aussenwasserzaehler) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 09:46:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 09:46:44.302 after 0.013233 --------
2020.04.19 09:51:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 09:51:44.172 ---------------------
2020.04.19 09:51:44 1: [Freezemon] myfreezemon: possible freeze starting at 09:51:43, delay is 1.171 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 09:51:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 09:51:44.187 after 0.015676 --------
2020.04.19 09:56:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 09:56:44.116 ---------------------
2020.04.19 09:56:44 1: [Freezemon] myfreezemon: possible freeze starting at 09:56:43, delay is 1.116 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 09:56:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 09:56:44.128 after 0.012528 --------
2020.04.19 09:57:04 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 09:57:04.320 ---------------------
2020.04.19 09:57:04 1: [Freezemon] myfreezemon: possible freeze starting at 09:56:59, delay is 5.319 possibly caused by: tmr-echodevice_LoginStart(Echos) tmr-ENIGMA2_GetStatus(Dreambox_Schlafzimmer) tmr-SYSMON_Update(Sysmon) tmr-ESPEasy_statusRequest(ESPEasy_Schuppen_Garage_geschlossen) tmr-at_Exec(Solartimer)
2020.04.19 09:57:04 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 09:57:04.340 after 0.020747 --------
2020.04.19 10:01:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 10:01:44.297 ---------------------
2020.04.19 10:01:44 1: [Freezemon] myfreezemon: possible freeze starting at 10:01:43, delay is 1.297 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 10:01:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 10:01:44.310 after 0.012888 --------
2020.04.19 10:06:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 10:06:44.075 ---------------------
2020.04.19 10:06:44 1: [Freezemon] myfreezemon: possible freeze starting at 10:06:43, delay is 1.075 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 10:06:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 10:06:44.088 after 0.012399 --------
2020.04.19 10:11:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 10:11:44.359 ---------------------
2020.04.19 10:11:44 1: [Freezemon] myfreezemon: possible freeze starting at 10:11:43, delay is 1.359 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-PRESENCE_StartLocalScan(Whirlpool_Ping) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 10:11:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 10:11:44.379 after 0.019962 --------
2020.04.19 10:12:15 0: Strange call for nonexistent : ReadFn
2020.04.19 10:16:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 10:16:44.156 ---------------------
2020.04.19 10:16:44 5: [Freezemon] myfreezemon found something that's not a REF HMUARTLGW_CheckCredits 
2020.04.19 10:16:44 1: [Freezemon] myfreezemon: possible freeze starting at 10:16:43, delay is 1.155 possibly caused by: tmr-DbLog_execmemcache(LOGDB) tmr-HMUARTLGW_CheckCredits(HM_Gateway) tmr-PRESENCE_StartLocalScan(Tanja) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 10:16:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 10:16:44.169 after 0.013558 --------
2020.04.19 10:21:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 10:21:44.436 ---------------------
2020.04.19 10:21:44 5: [Freezemon] myfreezemon found something that's not a REF HMUARTLGW_CheckCredits 
2020.04.19 10:21:44 1: [Freezemon] myfreezemon: possible freeze starting at 10:21:43, delay is 1.436 possibly caused by: tmr-PRESENCE_StartLocalScan(Strom_Gateways_Ping) tmr-DbLog_execmemcache(LOGDB) tmr-HMUARTLGW_CheckCredits(HM_Gateway) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 10:21:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 10:21:44.450 after 0.013816 --------
2020.04.19 10:27:15 0: Strange call for nonexistent : ReadFn
2020.04.19 10:31:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 10:31:44.108 ---------------------
2020.04.19 10:31:44 1: [Freezemon] myfreezemon: possible freeze starting at 10:31:43, delay is 1.108 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 10:31:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 10:31:44.122 after 0.013953 --------
2020.04.19 10:37:15 0: Strange call for nonexistent : ReadFn
2020.04.19 10:41:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 10:41:44.227 ---------------------
2020.04.19 10:41:44 1: [Freezemon] myfreezemon: possible freeze starting at 10:41:43, delay is 1.227 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Solartimer) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 10:41:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 10:41:44.241 after 0.013415 --------
2020.04.19 10:42:15 0: Strange call for nonexistent : ReadFn
2020.04.19 10:51:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 10:51:44.117 ---------------------
2020.04.19 10:51:44 1: [Freezemon] myfreezemon: possible freeze starting at 10:51:43, delay is 1.117 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 10:51:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 10:51:44.130 after 0.013069 --------
2020.04.19 11:01:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 11:01:44.020 ---------------------
2020.04.19 11:01:44 1: [Freezemon] myfreezemon: possible freeze starting at 11:01:43, delay is 1.019 possibly caused by: tmr-CODE(0x5e30578)(__ANON__) tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 11:01:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 11:01:44.032 after 0.012865 --------
2020.04.19 11:08:52 1: RMDIR: ./restoreDir/save/2020-03-26
2020.04.19 11:11:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 11:11:44.297 ---------------------
2020.04.19 11:11:44 1: [Freezemon] myfreezemon: possible freeze starting at 11:11:43, delay is 1.297 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 11:11:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 11:11:44.312 after 0.015596 --------
2020.04.19 11:16:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 11:16:44.260 ---------------------
2020.04.19 11:16:44 1: [Freezemon] myfreezemon: possible freeze starting at 11:16:43, delay is 1.26 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 11:16:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 11:16:44.275 after 0.015224 --------
2020.04.19 11:21:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 11:21:44.420 ---------------------
2020.04.19 11:21:44 1: [Freezemon] myfreezemon: possible freeze starting at 11:21:43, delay is 1.42 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Update_Ebusd_10min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-at_Exec(Feuermelder_Timer)
2020.04.19 11:21:44 5: [Freezemon] myfreezemon: ----------- Ending Freeze handling at 2020.04.19 11:21:44.438 after 0.017372 --------
2020.04.19 11:26:44 5: [Freezemon] myfreezemon: ----------- Starting Freeze handling at 2020.04.19 11:26:44.042 ---------------------
2020.04.19 11:26:44 1: [Freezemon] myfreezemon: possible freeze starting at 11:26:43, delay is 1.042 possibly caused by: tmr-at_Exec(Heiztimer) tmr-at_Exec(Update_Ebusd) tmr-at_Exec(Update_Ebusd_5min) tmr-at_Exec(Fakelogtimer) tmr-at_Exec(Wertetimer_GH) tmr-PRESENCE_StartLocalScan(Strom_Gateways_Ping) tmr-at_Exec(Feuermelder_Timer)
FHEM auf RPI 4 4GB, HM-WLAN-Gateway, einige HM-Aktoren,2x EBUSD an Heizung+Solar, ESP8266 am Strom-,Gas-,Wasserzähler, in WLAN-Steckdosen und Relaisleisten, Sonoff S20, Shelly1,2 und 2.5,Lacrosse-Gateway und Sensoren,Sduino,Alexa-Fhem,Huawei PV mit Speicher, alles auf einem RPI und da geht noch mehr

KölnSolar

die Dreambox war aber auch immer im Spiel. Vielleicht einen separaten Thread eröffnen und das freeze-log posten ?  :-\

ZitatDas NPM Login wird komplett anders verarbeitet. Hier bringt ein zusätzliches keepalive überhaupt nichts.
Ok, was ich meinte sind die bei mir vorhandenen freezes2020.04.17 18:58:55 3: [Freezemon] freezedetect: possible freeze starting at 18:58:55, delay is 0.62 possibly caused by: tmr-echodevice_GetSettings(echomaster) tmr-Nina_Start(myNina) tmr-GPIO4_DeviceUpdateLoop(RPi_OW_WWL)
2020.04.17 18:59:55 3: [Freezemon] freezedetect: possible freeze starting at 18:59:55, delay is 0.391 possibly caused by: tmr-GPIO4_DeviceUpdateLoop(RPi_OW_VL) tmr-echodevice_GetSettings(echomaster) tmr-Nina_Start(myNina) tmr-statistics_PeriodChange(stat_wr) tmr-statistics_PeriodChange(stat_sl)
2020.04.17 19:02:55 3: [Freezemon] freezedetect: possible freeze starting at 19:02:55, delay is 0.948 possibly caused by: tmr-echodevice_GetSettings(echomaster) tmr-Nina_Start(myNina)
2020.04.18 11:01:29 3: [Freezemon] freezedetect: possible freeze starting at 11:01:29, delay is 0.892 possibly caused by: tmr-echodevice_GetSettings(echomaster) tmr-Nina_Start(myNina) tmr-USBWRF_GetUpdate(Fronius)
2020.04.18 21:04:49 3: [Freezemon] freezedetect: possible freeze starting at 21:04:49, delay is 0.323 possibly caused by: tmr-echodevice_GetSettings(echomaster) tmr-Nina_Start(myNina)
2020.04.19 18:18:34 3: [Freezemon] freezedetect: possible freeze starting at 18:18:34, delay is 0.485 possibly caused by: tmr-echodevice_GetSettings(echomaster) tmr-Nina_Start(myNina) tmr-USBWRF_GetUpdate(Fronius)
2020.04.19 18:35:35 3: [Freezemon] freezedetect: possible freeze starting at 18:35:35, delay is 0.474 possibly caused by: tmr-echodevice_GetSettings(echomaster) tmr-Nina_Start(myNina) tmr-USBWRF_GetUpdate(Fronius) tmr-GPIO4_DeviceUpdateLoop(RPi_OW_KS)


Komisch, dass meine Nina immer dabei ist. Ne Idee warum dessen blocking call in die Ausführung des echodevice funkt ? :-\
[Freezemon] freezedetect: possible freeze starting at 18:35:35, delay is 0.474 possibly caused by: tmr-echodevice_GetSettings(echomaster) tmr-Nina_Start(myNina) tmr-USBWRF_GetUpdate(Fronius) tmr-GPIO4_DeviceUpdateLoop(RPi_OW_KS)
2020.04.19 18:35:34.332 5: [echomaster] [echodevice_GetSettings] start refresh settings
2020.04.19 18:35:34.332 4: [echomaster] [echodevice_SendCommand] [getnotifications] START
2020.04.19 18:35:34.333 4: [echomaster] [echodevice_SendCommand] [getnotifications] PushToCmdQueue SendURL =https://layla.amazon.de/api/notifications
2020.04.19 18:35:34.333 4: [echomaster] [echodevice_SendCommand] [getnotifications] PushToCmdQueue SendData=
2020.04.19 18:35:34.334 4: [echomaster] [echodevice_HandleCmdQueue] [getnotifications] send command=https://layla.amazon.de/api/notifications Data=
2020.04.19 18:35:34.335 5: HttpUtils url=https://layla.amazon.de/api/notifications
2020.04.19 18:35:34.335 5: HttpUtils request header:
GET /api/notifications HTTP/1.1
Host: layla.amazon.de
Accept-Encoding: gzip,deflate
User-Agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:62.0) Gecko/20100101 Firefox/62.0
Accept-Language: de,en-US;q=0.7,en;q=0.3
DNT: 1
Connection: keep-alive
Upgrade-Insecure-Requests: 1
Cookie:meinCokie
csrf: -642580457
Content-Type: application/json; charset=UTF-8

2020.04.19 18:35:34.336 4: [echomaster] [echodevice_SendCommand] [alarmvolume] START
2020.04.19 18:35:34.336 4: [echomaster] [echodevice_SendCommand] [alarmvolume] PushToCmdQueue SendURL =https://layla.amazon.de/api/device-notification-state?_=1587314134
2020.04.19 18:35:34.336 4: [echomaster] [echodevice_SendCommand] [alarmvolume] PushToCmdQueue SendData=
2020.04.19 18:35:34.337 4: [echomaster] [echodevice_SendCommand] [bluetoothstate] START
2020.04.19 18:35:34.338 4: [echomaster] [echodevice_SendCommand] [bluetoothstate] PushToCmdQueue SendURL =https://layla.amazon.de/api/bluetooth?cached=true&_=1587314134
2020.04.19 18:35:34.338 4: [echomaster] [echodevice_SendCommand] [bluetoothstate] PushToCmdQueue SendData=
2020.04.19 18:35:34.339 4: [echomaster] [echodevice_SendCommand] [getdnd] START
2020.04.19 18:35:34.339 4: [echomaster] [echodevice_SendCommand] [getdnd] PushToCmdQueue SendURL =https://layla.amazon.de/api/dnd/device-status-list?_=1587314134
2020.04.19 18:35:34.339 4: [echomaster] [echodevice_SendCommand] [getdnd] PushToCmdQueue SendData=
2020.04.19 18:35:34.340 4: [echomaster] [echodevice_SendCommand] [wakeword] START
2020.04.19 18:35:34.341 4: [echomaster] [echodevice_SendCommand] [wakeword] PushToCmdQueue SendURL =https://layla.amazon.de/api/wake-word?_=1587314134
2020.04.19 18:35:34.341 4: [echomaster] [echodevice_SendCommand] [wakeword] PushToCmdQueue SendData=
2020.04.19 18:35:34.341 4: [echomaster] [echodevice_SendCommand] [listitems_task] START
2020.04.19 18:35:34.342 4: [echomaster] [echodevice_SendCommand] [listitems_task] PushToCmdQueue SendURL =https://layla.amazon.de/api/todos?size=100&startTime=&endTime=&completed=false&type=TASK&deviceSerialNumber=&deviceType=&_=1587314134
2020.04.19 18:35:34.342 4: [echomaster] [echodevice_SendCommand] [listitems_task] PushToCmdQueue SendData=TASK
2020.04.19 18:35:34.343 4: [echomaster] [echodevice_SendCommand] [listitems_shopping] START
2020.04.19 18:35:34.343 4: [echomaster] [echodevice_SendCommand] [listitems_shopping] PushToCmdQueue SendURL =https://layla.amazon.de/api/todos?size=100&startTime=&endTime=&completed=false&type=SHOPPING_ITEM&deviceSerialNumber=&deviceType=&_=1587314134
2020.04.19 18:35:34.343 4: [echomaster] [echodevice_SendCommand] [listitems_shopping] PushToCmdQueue SendData=SHOPPING_ITEM
2020.04.19 18:35:34.344 4: [echomaster] [echodevice_SendCommand] [getdevicesettings] START
2020.04.19 18:35:34.344 4: [echomaster] [echodevice_SendCommand] [getdevicesettings] PushToCmdQueue SendURL =https://layla.amazon.de/api/device-preferences
2020.04.19 18:35:34.344 4: [echomaster] [echodevice_SendCommand] [getdevicesettings] PushToCmdQueue SendData=
2020.04.19 18:35:34.345 4: [echomaster] [echodevice_SendCommand] [getisonline] START
2020.04.19 18:35:34.345 4: [echomaster] [echodevice_SendCommand] [getisonline] PushToCmdQueue SendURL =https://layla.amazon.de/api/devices-v2/device?cached=true&_=1587314134
2020.04.19 18:35:34.345 4: [echomaster] [echodevice_SendCommand] [getisonline] PushToCmdQueue SendData=
2020.04.19 18:35:34.346 4: [echomaster] [echodevice_SendCommand] [devicesstate] START
2020.04.19 18:35:34.347 4: [echomaster] [echodevice_SendCommand] [devicesstate] PushToCmdQueue SendURL =https://layla.amazon.de/api/devices-v2/device?cached=true&_=1587314134
2020.04.19 18:35:34.347 4: [echomaster] [echodevice_SendCommand] [devicesstate] PushToCmdQueue SendData=
2020.04.19 18:35:34.347 4: [echomaster] [echodevice_SendCommand] [account] START
2020.04.19 18:35:34.348 4: [echomaster] [echodevice_SendCommand] [account] PushToCmdQueue SendURL =https://alexa-comms-mobile-service.amazon.com/accounts
2020.04.19 18:35:34.348 4: [echomaster] [echodevice_SendCommand] [account] PushToCmdQueue SendData=
2020.04.19 18:35:34.349 4: [echomaster] [echodevice_SendLoginCommand] [cookielogin6]
2020.04.19 18:35:34.350 5: HttpUtils url=https://layla.amazon.de/api/bootstrap
2020.04.19 18:35:34.350 4: IP: layla.amazon.de -> 99.84.157.56
2020.04.19 18:35:34.352 5: [echomaster] [echodevice_GetSettings] refresh voice command
2020.04.19 18:35:34.352 4: [echomaster] [echodevice_SendCommand] [activities] START
2020.04.19 18:35:34.352 4: [echomaster] [echodevice_SendCommand] [activities] PushToCmdQueue SendURL =https://layla.amazon.de/api/activities?startTime=&size=50&offset=1&_=1587314134
2020.04.19 18:35:34.352 4: [echomaster] [echodevice_SendCommand] [activities] PushToCmdQueue SendData=
2020.04.19 18:35:34.353 4: [echomaster] [echodevice_SendCommand] [getbehavior] START
2020.04.19 18:35:34.354 4: [echomaster] [echodevice_SendCommand] [getbehavior] PushToCmdQueue SendURL =https://layla.amazon.de/api/behaviors/automations?limit=100
2020.04.19 18:35:34.354 4: [echomaster] [echodevice_SendCommand] [getbehavior] PushToCmdQueue SendData=
2020.04.19 18:35:34.354 4: [echomaster] [echodevice_SendCommand] [getsettingstraffic] START
2020.04.19 18:35:34.355 4: [echomaster] [echodevice_SendCommand] [getsettingstraffic] PushToCmdQueue SendURL =https://layla.amazon.de/api/traffic/settings
2020.04.19 18:35:34.355 4: [echomaster] [echodevice_SendCommand] [getsettingstraffic] PushToCmdQueue SendData=
2020.04.19 18:35:34.356 4: [echomaster] [echodevice_GetSettings] Timer INTERVAL = 60
2020.04.19 18:35:34.380 4: BlockingCall (Nina_Run): created child (16938), uses telnetForBlockingFn_1586790614 to connect back
2020.04.19 18:35:34.422 4: Connection accepted from telnetForBlockingFn_1586790614_127.0.0.1_40506
2020.04.19 18:35:34.426 5: Cmd: >{BlockingRegisterTelnet($cl,247973)}<
2020.04.19 18:35:35.424 5: HttpUtils request header:
GET /api/bootstrap HTTP/1.1
Host: layla.amazon.de
User-Agent: fhem
Accept-Encoding: gzip,deflate
Connection: Close
Cookie: meinCookie

bzw. warum der request eine s später kommt ?

Und warum die freeze-Dauer so gar nicht passt frag ich mal Oli
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

blade-of-fire

#3944
Guten Morgen zusammen.
Ich habe ein Problem mit der Moduldatei 37_echodevice.pm.
Seit Anfang des Jahres ist die Datei ja mit im FHEM-Repo, sodass Aktualiserungen per Fhem Update passieren. Mir ist gestern aufgefallen, dass meine Datei immer noch auf dem alten Stand 0.0.59 war.
Ich habe dann den Befehl "update 37_echodevice.pm" durchgeführt, was aber dazu führte, dass die Datei aus dem FHEM-verzeichnis gelöscht wurde.
Nach Recherche habe ich nichts gefunden, was das auslösen könnte. Ein erneutes Update hat auch nichts geholfen.
Muss ein bestimmter Befehl eingegeben werden, wenn man Module neue Module in FHEM aktivieren will?

Ich habe auch schon einmal ein "update force" durchgeführt. Dies hat zwar auch nicht dazu geführt, dass die Modul-Datei heruntergeladen wurde, allerdings hatte danach das update der Moduls geklappt und ich konnte erfolgreich Amazon und die Echos verbinden.
Nach einem Fhem Crash gestern abend (andere Geschichte) wurde aber dann die Moduldatei "37_echodevice.pm" beim Booten wieder gelöscht... Ich verstehe nicht, warum.

Im Log steht dazu nichts. Nur eben dass er die ganze echodevices nicht finden konnte, weil sie aufgrund des fehlenden Moduls nicht mehr geladen werden können.

Noch ein Hinweis. Ich benutzte configDB und hatte die echodevice-Moduldatei als fileimport in die DB importiert.

Ich bin mir nicht ganz sicher, ob das tatsächlich ein Thema ist, was direkt mit diesem Modul zu tun hat, aber bisher hatte ich solche Probleme mit anderen Modulen nicht.
Danke schonmal.
VM mit Ubuntu und FHEM-Instanz (Hauptinstanz)
FHEM2FHEM
Raspberry Pi 3 B+ mit Eigenbau-Platine + Relais-Platine + Cul-Stick + FHEMDuino