70_ENPHASE - state "Daten zu alt"

peterboeckmann · 19 Mai 2025, 07:48:06

Hallo zusammen,

seit letzter Nacht bekomme ich keine Daten mehr aus dem ENPHASE ausgelesen.
Im Log erscheint immer wieder folgendes:

Code Auswählen

2025.05.19 00:20:16 1: readingsUpdate(Enphase,state,Timeout, will try again) missed to call readingsBeginUpdate first.
2025.05.19 00:20:16 1: stacktrace:
2025.05.19 00:20:16 1:     main::readingsBulkUpdate            called by ./FHEM/70_ENPHASE.pm (1302)
2025.05.19 00:20:16 1:     main::checkHttpResponse             called by ./FHEM/70_ENPHASE.pm (463)
2025.05.19 00:20:16 1:     main::ENPHASE_getLiveDaten          called by FHEM/HttpUtils.pm (112)
2025.05.19 00:20:16 1:     main::HttpUtils_TimeoutErr          called by fhem.pl (3511)
2025.05.19 00:20:16 1:     main::HandleTimeout                 called by fhem.pl (710)
2025.05.19 00:22:01 1: readingsUpdate(Enphase,state,Timeout, will try again) missed to call readingsBeginUpdate first.
2025.05.19 00:22:01 1: stacktrace:
2025.05.19 00:22:01 1:     main::readingsBulkUpdate            called by ./FHEM/70_ENPHASE.pm (1302)
2025.05.19 00:22:01 1:     main::checkHttpResponse             called by ./FHEM/70_ENPHASE.pm (463)
2025.05.19 00:22:01 1:     main::ENPHASE_getLiveDaten          called by FHEM/HttpUtils.pm (112)
2025.05.19 00:22:01 1:     main::HttpUtils_TimeoutErr          called by fhem.pl (3511)
2025.05.19 00:22:01 1:     main::HandleTimeout                 called by fhem.pl (710)

Mit verbose=5 steht folgendes im Log:

Code Auswählen

2025.05.19 07:46:45 4: Enphase (Enphase): ==> start livedaten
2025.05.19 07:46:46 5: Enphase (Enphase): ==> header: HTTP/1.1 200 OK
Server: openresty/1.17.8.1
Date: Mon, 19 May 2025 05:46:25 GMT
Content-Type: application/json
Connection: close
Pragma: no-cache
Expires: 1
Cache-Control: no-cache
Strict-Transport-Security: max-age=63072000; includeSubdomains
X-Frame-Options: DENY
X-Content-Type-Options: nosniff
2025.05.19 07:46:46 5: {"production":[{"type":"inverters","activeCount":0,"readingTime":1747631430,"wNow":0,"whLifetime":0},{"type":"eim","activeCount":1,"measurementType":"production","readingTime":1747633585,"wNow":739.679,"whLifetime":12503184.306,"varhLeadLifetime":753007.029,"varhLagLifetime":967967.635,"vahLifetime":14369472.331,"rmsCurrent":3.535,"rmsVoltage":700.351,"reactPwr":-27.238,"apprntPwr":826.755,"pwrFactor":0.91,"whToday":436.306,"whLastSevenDays":360502.306,"vahToday":524.331,"varhLeadToday":38.029,"varhLagToday":1.635}],"consumption":[{"type":"eim","activeCount":1,"measurementType":"total-consumption","readingTime":1747633585,"wNow":774.671,"whLifetime":11577002.652,"varhLeadLifetime":6918124.431,"varhLagLifetime":-603080.368,"vahLifetime":19931516.545,"rmsCurrent":4.144,"rmsVoltage":700.033,"reactPwr":-419.412,"apprntPwr":963.995,"pwrFactor":0.81,"whToday":483.652,"whLastSevenDays":94509.652,"vahToday":749.545,"varhLeadToday":365.431,"varhLagToday":122.0},{"type":"eim","activeCount":1,"measurementType":"net-consumption","readingTime":1747633585,"wNow":34.992,"whLifetime":-738831.299,"varhLeadLifetime":7671131.46,"varhLagLifetime":364887.266,"vahLifetime":19931516.545,"rmsCurrent":0.609,"rmsVoltage":700.033,"reactPwr":-392.174,"apprntPwr":138.991,"pwrFactor":0.11,"whToday":0,"whLastSevenDays":0,"vahToday":0,"varhLeadToday":0,"varhLagToday":0}],"storage":[{"type":"acb","activeCount":0,"readingTime":0,"wNow":0,"whNow":0,"state":"idle"}]}
2025.05.19 07:46:46 5: Enphase (Enphase): ==> Error: 
2025.05.19 07:46:46 5: Enphase (Enphase): ==> ReturnCode: 200
2025.05.19 07:46:46 5: Enphase (Enphase): ==> Readingtime: 1747633585
2025.05.19 07:46:46 5: Enphase (Enphase): ==> Readingtime: 19
2025.05.19 07:46:46 5: Enphase (Enphase): ==> Readingtime: 19
2025.05.19 07:46:46 5: Enphase (Enphase): ==> Readingtime: Mon May 19 07:46:25 2025
2025.05.19 07:46:47 4: Enphase (Enphase): ==> start newlive
2025.05.19 07:46:47 5: Enphase (Enphase): ==> header: HTTP/1.1 200 OK
Server: openresty/1.17.8.1
Date: Mon, 19 May 2025 05:46:26 GMT
Content-Length: 3354
Connection: close
Strict-Transport-Security: max-age=63072000; includeSubdomains
X-Frame-Options: DENY
X-Content-Type-Options: nosniff
2025.05.19 07:46:47 5: {
    "connection": {
        "mqtt_state": "connected",
        "prov_state": "configured",
        "auth_state": "ok",
        "sc_stream": "enabled",
        "sc_debug": "disabled"
    },
    "meters": {
        "last_update": 1747633586,
        "soc": 13,
        "main_relay_state": 1,
        "gen_relay_state": 5,
        "backup_bat_mode": 1,
        "backup_soc": 0,
        "is_split_phase": 0,
        "phase_count": 3,
        "enc_agg_soc": 13,
        "enc_agg_energy": 910,
        "acb_agg_soc": 0,
        "acb_agg_energy": 0,
        "pv": {
            "agg_p_mw": 755129,
            "agg_s_mva": 843383,
            "agg_p_ph_a_mw": 256058,
            "agg_p_ph_b_mw": 246500,
            "agg_p_ph_c_mw": 252570,
            "agg_s_ph_a_mva": 289457,
            "agg_s_ph_b_mva": 268411,
            "agg_s_ph_c_mva": 285514
        },
        "storage": {
            "agg_p_mw": -158000,
            "agg_s_mva": -242546,
            "agg_p_ph_a_mw": -77000,
            "agg_p_ph_b_mw": -81000,
            "agg_p_ph_c_mw": 0,
            "agg_s_ph_a_mva": -109622,
            "agg_s_ph_b_mva": -101468,
            "agg_s_ph_c_mva": -31457
        },
        "grid": {
            "agg_p_mw": 32434,
            "agg_s_mva": 123591,
            "agg_p_ph_a_mw": -98342,
            "agg_p_ph_b_mw": 261791,
            "agg_p_ph_c_mw": -131017,
            "agg_s_ph_a_mva": -191220,
            "agg_s_ph_b_mva": 545399,
            "agg_s_ph_c_mva": -230589
        },
        "load": {
            "agg_p_mw": 629563,
            "agg_s_mva": 724428,
            "agg_p_ph_a_mw": 80716,
            "agg_p_ph_b_mw": 427291,
            "agg_p_ph_c_mw": 121553,
            "agg_s_ph_a_mva": -11385,
            "agg_s_ph_b_mva": 712342,
            "agg_s_ph_c_mva": 23468
        },
        "generator": {
            "agg_p_mw": 0,
            "agg_s_mva": 0,
            "agg_p_ph_a_mw": 0,
            "agg_p_ph_b_mw": 0,
            "agg_p_ph_c_mw": 0,
            "agg_s_ph_a_mva": 0,
            "agg_s_ph_b_mva": 0,
            "agg_s_ph_c_mva": 0
        }
    },
    "tasks": {
        "task_id": 124808711,
        "timestamp": 1747633420
    },
    "counters": {
        "main_CfgLoad": 1,
        "main_CfgChanged": 1,
        "main_taskUpdate": 7,
        "MqttClient_respond": 14,
        "MqttClient_msgarrvd": 7,
        "MqttClient_create": 1,
        "MqttClient_setCallbacks": 1,
        "MqttClient_connect": 1,
        "MqttClient_subscribe": 1,
        "SSL_Keys_Create": 1,
        "sc_SendStreamCtrl": 1,
        "sc_SendDemandRspCtrl": 1,
        "rest_Status": 365
    },
    "dry_contacts": {
        "": {
            "dry_contact_id": "",
            "dry_contact_type": "",
            "dry_contact_load_name": "\u0006",
            "dry_contact_status": 0
        },
        "": {
            "dry_contact_id": "",
            "dry_contact_type": "",
            "dry_contact_load_name": "",
            "dry_contact_status": 0
        },
        "": {
            "dry_contact_id": "",
            "dry_contact_type": "",
            "dry_contact_load_name": "",
            "dry_contact_status": 0
        },
        "": {
            "dry_contact_id": "",
            "dry_contact_type": "",
            "dry_contact_load_name": "",
            "dry_contact_status": 0
        }
    }
}
2025.05.19 07:46:47 5: Enphase (Enphase): ==> Error: 
2025.05.19 07:46:47 5: Enphase (Enphase): ==> ReturnCode: 200
2025.05.19 07:46:47 5: Enphase (Enphase): ==> Daten zu alt
2025.05.19 07:46:47 5: Enphase (Enphase): ==> Readingtime: 1747633586
2025.05.19 07:46:47 5: Enphase (Enphase): ==> Readingtime: 1747633607.94919
2025.05.19 07:46:47 5: Enphase (Enphase): ==> Readingtime: 15
2025.05.19 07:46:50 4: Enphase (Enphase): ==> start livedaten
2025.05.19 07:46:50 5: Enphase (Enphase): ==> header: HTTP/1.1 200 OK
Server: openresty/1.17.8.1
Date: Mon, 19 May 2025 05:46:29 GMT
Content-Type: application/json
Connection: close
Pragma: no-cache
Expires: 1
Cache-Control: no-cache
Strict-Transport-Security: max-age=63072000; includeSubdomains
X-Frame-Options: DENY
X-Content-Type-Options: nosniff
2025.05.19 07:46:50 5: {"production":[{"type":"inverters","activeCount":0,"readingTime":1747631430,"wNow":0,"whLifetime":0},{"type":"eim","activeCount":1,"measurementType":"production","readingTime":1747633589,"wNow":742.281,"whLifetime":12503185.177,"varhLeadLifetime":753007.069,"varhLagLifetime":967967.636,"vahLifetime":14369473.305,"rmsCurrent":3.555,"rmsVoltage":700.599,"reactPwr":-29.419,"apprntPwr":831.45,"pwrFactor":0.89,"whToday":437.177,"whLastSevenDays":360503.177,"vahToday":525.305,"varhLeadToday":38.069,"varhLagToday":1.636}],"consumption":[{"type":"eim","activeCount":1,"measurementType":"total-consumption","readingTime":1747633589,"wNow":762.367,"whLifetime":11577003.547,"varhLeadLifetime":6918125.014,"varhLagLifetime":-603080.199,"vahLifetime":19931517.684,"rmsCurrent":4.121,"rmsVoltage":700.278,"reactPwr":-418.769,"apprntPwr":959.005,"pwrFactor":0.79,"whToday":484.547,"whLastSevenDays":94510.547,"vahToday":750.684,"varhLeadToday":366.014,"varhLagToday":122.0},{"type":"eim","activeCount":1,"measurementType":"net-consumption","readingTime":1747633589,"wNow":20.086,"whLifetime":-738831.275,"varhLeadLifetime":7671132.083,"varhLagLifetime":364887.437,"vahLifetime":19931517.684,"rmsCurrent":0.566,"rmsVoltage":700.278,"reactPwr":-389.349,"apprntPwr":129.106,"pwrFactor":0.25,"whToday":0,"whLastSevenDays":0,"vahToday":0,"varhLeadToday":0,"varhLagToday":0}],"storage":[{"type":"acb","activeCount":0,"readingTime":0,"wNow":0,"whNow":0,"state":"idle"}]}
2025.05.19 07:46:50 5: Enphase (Enphase): ==> Error: 
2025.05.19 07:46:50 5: Enphase (Enphase): ==> ReturnCode: 200
2025.05.19 07:46:50 5: Enphase (Enphase): ==> Readingtime: 1747633589
2025.05.19 07:46:50 5: Enphase (Enphase): ==> Readingtime: 19
2025.05.19 07:46:50 5: Enphase (Enphase): ==> Readingtime: 19
2025.05.19 07:46:50 5: Enphase (Enphase): ==> Readingtime: Mon May 19 07:46:29 2025
2025.05.19 07:46:51 4: Enphase (Enphase): ==> start newlive
2025.05.19 07:46:51 5: Enphase (Enphase): ==> header: HTTP/1.1 200 OK
Server: openresty/1.17.8.1
Date: Mon, 19 May 2025 05:46:30 GMT
Content-Length: 3354
Connection: close
Strict-Transport-Security: max-age=63072000; includeSubdomains
X-Frame-Options: DENY
X-Content-Type-Options: nosniff
2025.05.19 07:46:51 5: {
    "connection": {
        "mqtt_state": "connected",
        "prov_state": "configured",
        "auth_state": "ok",
        "sc_stream": "enabled",
        "sc_debug": "disabled"
    },
    "meters": {
        "last_update": 1747633590,
        "soc": 13,
        "main_relay_state": 1,
        "gen_relay_state": 5,
        "backup_bat_mode": 1,
        "backup_soc": 0,
        "is_split_phase": 0,
        "phase_count": 3,
        "enc_agg_soc": 13,
        "enc_agg_energy": 910,
        "acb_agg_soc": 0,
        "acb_agg_energy": 0,
        "pv": {
            "agg_p_mw": 727760,
            "agg_s_mva": 835775,
            "agg_p_ph_a_mw": 242403,
            "agg_p_ph_b_mw": 237457,
            "agg_p_ph_c_mw": 247899,
            "agg_s_ph_a_mva": 285180,
            "agg_s_ph_b_mva": 266240,
            "agg_s_ph_c_mva": 284355
        },
        "storage": {
            "agg_p_mw": -155000,
            "agg_s_mva": -262593,
            "agg_p_ph_a_mw": -74000,
            "agg_p_ph_b_mw": -81000,
            "agg_p_ph_c_mw": 0,
            "agg_s_ph_a_mva": -116627,
            "agg_s_ph_b_mva": -109618,
            "agg_s_ph_c_mva": -36349
        },
        "grid": {
            "agg_p_mw": 4312,
            "agg_s_mva": 117882,
            "agg_p_ph_a_mw": -114270,
            "agg_p_ph_b_mw": 251594,
            "agg_p_ph_c_mw": -133012,
            "agg_s_ph_a_mva": -205685,
            "agg_s_ph_b_mva": 547587,
            "agg_s_ph_c_mva": -224020
        },
        "load": {
            "agg_p_mw": 577072,
            "agg_s_mva": 691064,
            "agg_p_ph_a_mw": 54133,
            "agg_p_ph_b_mw": 408051,
            "agg_p_ph_c_mw": 114887,
            "agg_s_ph_a_mva": -37132,
            "agg_s_ph_b_mva": 704209,
            "agg_s_ph_c_mva": 23986
        },
        "generator": {
            "agg_p_mw": 0,
            "agg_s_mva": 0,
            "agg_p_ph_a_mw": 0,
            "agg_p_ph_b_mw": 0,
            "agg_p_ph_c_mw": 0,
            "agg_s_ph_a_mva": 0,
            "agg_s_ph_b_mva": 0,
            "agg_s_ph_c_mva": 0
        }
    },
    "tasks": {
        "task_id": 124808711,
        "timestamp": 1747633420
    },
    "counters": {
        "main_CfgLoad": 1,
        "main_CfgChanged": 1,
        "main_taskUpdate": 7,
        "MqttClient_respond": 14,
        "MqttClient_msgarrvd": 7,
        "MqttClient_create": 1,
        "MqttClient_setCallbacks": 1,
        "MqttClient_connect": 1,
        "MqttClient_subscribe": 1,
        "SSL_Keys_Create": 1,
        "sc_SendStreamCtrl": 1,
        "sc_SendDemandRspCtrl": 1,
        "rest_Status": 366
    },
    "dry_contacts": {
        "": {
            "dry_contact_id": "",
            "dry_contact_type": "",
            "dry_contact_load_name": "\u0006",
            "dry_contact_status": 0
        },
        "": {
            "dry_contact_id": "",
            "dry_contact_type": "",
            "dry_contact_load_name": "",
            "dry_contact_status": 0
        },
        "": {
            "dry_contact_id": "",
            "dry_contact_type": "",
            "dry_contact_load_name": "",
            "dry_contact_status": 0
        },
        "": {
            "dry_contact_id": "",
            "dry_contact_type": "",
            "dry_contact_load_name": "",
            "dry_contact_status": 0
        }
    }
}
2025.05.19 07:46:51 5: Enphase (Enphase): ==> Error: 
2025.05.19 07:46:51 5: Enphase (Enphase): ==> ReturnCode: 200
2025.05.19 07:46:51 5: Enphase (Enphase): ==> Daten zu alt
2025.05.19 07:46:51 5: Enphase (Enphase): ==> Readingtime: 1747633590
2025.05.19 07:46:51 5: Enphase (Enphase): ==> Readingtime: 1747633611.97542
2025.05.19 07:46:51 5: Enphase (Enphase): ==> Readingtime: 15

Ergebnis ist dann der state Daten zu alt".

Hat jemand eine Idee, woran das liegen kann?
Der Zugriff auf die Weboberfläche funktioniert nach Login via Token.

Vielen Dank und viele Grüße,
Peter

peterboeckmann · 19 Mai 2025, 08:42:36

Hallo zusammen,

ich habe das attr readingtimevariancesec auf 86400 festgelegt. Nun werden die Daten wieder ausgelesen.

Keine Ahnung, ob das eine sinnvolle Lösung ist, aber es funktioniert.

Viele Grüße,
Peter

rudolfkoenig · 19 Mai 2025, 09:22:57

Wann war das letzte FHEM update?

Christian83 · 19 Mai 2025, 15:52:25

Hallo Peter,

du hast einen Zeitversatz zwischen Systemzeit und der Zeit, die in der Antwort (vom Envoy Gateway) steht.

Code Auswählen

2025.05.19 07:46:51 5: Enphase (Enphase): ==> Daten zu alt
2025.05.19 07:46:51 5: Enphase (Enphase): ==> Readingtime: 1747633590
2025.05.19 07:46:51 5: Enphase (Enphase): ==> Readingtime: 1747633611.97542

readingtimevariancesec ist Standard auf 15 Sekunden gesetzt. Bei dir sind es 22 Sekunden Unterschied.
Stell es am Besten auf 30 sec.
Das Envoy Gateway hat immer mal Probleme mit der "echten" Uhrzeit.

Gruß,

Christian

FHEM Forum

70_ENPHASE - state "Daten zu alt"

peterboeckmann

peterboeckmann

rudolfkoenig

Christian83