[Gelöst] Ungeplante Fhem-Neustarts

Wernieman · 14 September 2022, 15:34:18

Da Du CPAN angesprochen hast, mal sämmtliche Module upgedatet?
https://blog.bartlweb.net/2018/03/installierte-perl-cpan-module-auf-einmal-aktualisieren/
System ist aktuell?

frank · 14 September 2022, 16:36:15

Zitat von: Gisbert am 14 September 2022, 11:27:23
HTTPMOD und at starte ich mit aligntime, so dass ich die alle schön zeitlich separieren kann.

da hatte ich einen gedankenfehler.
jedes "normale" sich wiederholende httpmod/at (ohne aligntime) startet ja etwa zu der zeit, wenn fhem startet.

fhem startet bei dir immer ca 90s nach einem crash.
es kann also in fhem nur eine routine in frage kommen, die explizit zur vollen stunde startet (zb auch aligntime=00:00:00). vermutlich kommt da gar nicht so viel in frage, aber eventuell schwer zu finden.

damals zu fhem@fritzbox-zeiten habe ich viel zeit damit verbracht, alle routinen schön zu separieren.
ich meine proplanta war zb so ein "ganove", der sich immer automatisch auf volle stunde synchronisiert hat.

Beta-User · 14 September 2022, 16:40:41

Es könnte aber auch sein, dass "reread" durch ein Timer-Gerät (hier konkret vermutlich: DOIF?) angeschubst wird...

frank · 14 September 2022, 16:59:57

vielleicht mal 10s vor jeder vollen stunde global verbose=5 setzen und 10s nach der vollen stunde wieder löschen.

Gisbert · 14 September 2022, 18:08:30

Hallo zusammen,

mein Linux (Debian) ist aktuell. Ich hab cpanm (als root, hoffentlich war das richtig) installiert und die outdated Module aufgefrischt. Das hat insgesamt eine Stunde benötigt, ca. 25 Module, meist eine Versionsnummer höher beim update. Danach hab ich den Server und damit Fhem neu gestartet.

global verbose=5 setze ich vor jeder vollen Stunde, danach wieder zurück - gute Idee.

Viele Grüße Gisbert

Gisbert · 14 September 2022, 18:36:05

Ein at hab ich nicht hinbekommen, aber ein DOIF, dann auf Minutenbasis:

Code Auswählen

defmod globalverbose5 DOIF ([+[1]:59]) (attr global verbose 5) \
DOELSEIF ([+[1]:01]) (attr global verbose 3)
attr globalverbose5 do always

Änderung:
set funktioniert nicht bei global, es ist ein Attribut.

Ergänzung:
Ich dachte, dass ich mit einem waittimer die Zeit von verbose=5 auf 10 Sekunden vor und nach einer vollen Stunde eingrenzen könnte, was aber wohl nicht funktioniert.

Änderung, als at-Definition (Klammern in Ausführungsteil sind nicht zulässig):

Code Auswählen

define gloverb5 at +*01:00:00 (attr -silent global verbose 5)
attr gloverb5 alignTime 00:59:50
define gloverb3 at +*01:00:00 (attr -silent global verbose 3)
attr gloverb3 alignTime 00:00:10

Gisbert · 15 September 2022, 07:57:30

Kurzes Update:

Nach dem Update von Perlpaketen mit cpanm und Server-Neustart liegt die RAM-Nutzung stabil bei knapp 50%; damit dürfte sich diese Aktion gelohnt hat haben. Lediglich ein Paket (DB_File) ließ sich nicht updaten.

verbose=5, 10 Sekunden vor und nach jeder vollen Stunde läuft, hier noch die Definition, falls später Mal jemand drüber stolpert:

Code Auswählen

define gloverb5 at +*01:00:00 attr -silent global verbose 5
attr gloverb5 alignTime 00:59:50
define gloverb3 at +*01:00:00 attr -silent global verbose 3
attr gloverb3 alignTime 00:00:10

Viele Grüße Gisbert

Gisbert · 15 September 2022, 12:25:59

So, es ist wieder soweit.
Gegen 12:00 hat wieder ein Neustart zugeschlagen, anbei der Auszug aus dem log mit verbose=5, startend bei 11:59:50 bis zum Neustart:

Code Auswählen

2022.09.15 11:59:50.004 5: Starting notify loop for global, 1 event(s), first is ATTR global verbose 5
2022.09.15 11:59:50.007 5: Battery.State: not on any display, ignoring notify
2022.09.15 11:59:50.010 5: [CO2Leverkusen] event:[ATTR global verbose 5], device:[global]
2022.09.15 11:59:50.039 5: [Rhasspy] NotifyFn called with event in global
2022.09.15 11:59:50.060 5: myUniFi (Unifi_Notify) - executed.
2022.09.15 11:59:50.075 5: End notify loop for global
2022.09.15 11:59:50.076 5: redefine at command gloverb5 as +*01:00:00 attr -silent global verbose 5
2022.09.15 11:59:50.079 5: Starting notify loop for gloverb5, 1 event(s), first is Next: 12:59:50
2022.09.15 11:59:50.080 5: Battery.State: not on any display, ignoring notify
2022.09.15 11:59:50.080 5: [CO2Leverkusen] event:[state: Next: 12:59:50], device:[gloverb5]
2022.09.15 11:59:50.100 5: End notify loop for gloverb5
2022.09.15 11:59:51.634 5: HMLAN_Send:  HM_CFG_LAN_Adapter I:K
2022.09.15 11:59:51.638 5: HMLAN/RAW: /HHM-LAN-IF,03C5,KEQ1024001,257643,257643,862A4037,0000,00

2022.09.15 11:59:51.638 5: HMLAN_Parse: HM_CFG_LAN_Adapter V:03C5 sNo:KEQ1024001 d:257643 O:257643 t:862A4037 IDcnt:0000 L:0 %
2022.09.15 11:59:51.640 5: Starting notify loop for HM_CFG_LAN_Adapter, 2 event(s), first is loadLvl: low
2022.09.15 11:59:51.640 5: Battery.State: not on any display, ignoring notify
2022.09.15 11:59:51.641 5: [CO2Leverkusen] event:[loadLvl: low], device:[HM_CFG_LAN_Adapter]
2022.09.15 11:59:51.641 5: [CO2Leverkusen] event:[Connect: 2022-09-14 17:25], device:[HM_CFG_LAN_Adapter]
2022.09.15 11:59:51.678 5: End notify loop for HM_CFG_LAN_Adapter
2022.09.15 11:59:55.556 5: [Shelly_status] issue a non-blocking call to http://192.168.2.37/status
2022.09.15 11:59:55.557 5: HttpUtils url=http://192.168.2.37/status NonBlocking via http
2022.09.15 11:59:55.557 4: IP: 192.168.2.37 -> 192.168.2.37
2022.09.15 11:59:55.600 5: HttpUtils request header:
GET /status HTTP/1.0
Host: 192.168.2.37
User-Agent: fhem
Accept-Encoding: gzip,deflate

2022.09.15 11:59:55.650 4: http://192.168.2.37/status: HTTP response code 200
2022.09.15 11:59:55.651 5: HttpUtils http://192.168.2.37/status: Got data, length: 1169
2022.09.15 11:59:55.651 5: HttpUtils response header:
HTTP/1.1 200 OK
Server: Mongoose/6.18
Connection: close
Content-Type: application/json
Content-Length: 1169
2022.09.15 11:59:55.651 5: [Shelly_proc1G] device myShelly3EM has returned data {"wifi_sta":{"connected":true,"ssid":"IoTSenhamEG","ip":"192.168.2.37","rssi":-41},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"11:59","unixtime":1663235994,"serial":14774,"has_update":false,"mac":"3494546ED7EA","cfg_changed_cnt":1,"actions_stats":{"skipped":0},"relays":[{"ison":false,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"overpower":false,"is_valid":true,"source":"input"}],"emeters":[{"power":172.13,"pf":0.76,"current":0.96,"voltage":236.41,"is_valid":true,"total":251166.7,"total_returned":0.0},{"power":105.54,"pf":0.40,"current":1.11,"voltage":236.87,"is_valid":true,"total":619237.2,"total_returned":0.0},{"power":16.85,"pf":0.65,"current":0.11,"voltage":237.22,"is_valid":true,"total":128890.7,"total_returned":0.0}],"total_power":294.52,"emeter_n":{"current":0.00,"ixsum":1.65,"mismatch":false,"is_valid":false},"fs_mounted":true,"v_data":1,"ct_calst":0,"update":{"status":"idle","has_update":false,"new_version":"20220830-080542/v1.12-3EM-gcf4f7c2","old_version":"20220830-080542/v1.12-3EM-gcf4f7c2"},"ram_total":49928,"ram_free":31976,"fs_size":233681,"fs_free":154616,"uptime":850502}
2022.09.15 11:59:55.657 5: Starting notify loop for myShelly3EM, 8 event(s), first is power_0: 172.13
2022.09.15 11:59:55.658 5: myShelly3EMCalculator : ElectricityCalculator Begin_________________________________________________________________________________________________________________________
2022.09.15 11:59:55.658 5: myShelly3EMCalculator : ElectricityCalculator - Notify - Trigger Dev Name                        : myShelly3EM
2022.09.15 11:59:55.659 5: Battery.State: not on any display, ignoring notify
2022.09.15 11:59:55.660 5: [CO2Leverkusen] event:[power_0: 172.13], device:[myShelly3EM]
2022.09.15 11:59:55.661 5: [CO2Leverkusen] event:[voltage_0: 236.41], device:[myShelly3EM]
2022.09.15 11:59:55.661 5: [CO2Leverkusen] event:[power_1: 105.54], device:[myShelly3EM]
2022.09.15 11:59:55.661 5: [CO2Leverkusen] event:[voltage_1: 236.87], device:[myShelly3EM]
2022.09.15 11:59:55.661 5: [CO2Leverkusen] event:[power_2: 16.85], device:[myShelly3EM]
2022.09.15 11:59:55.662 5: [CO2Leverkusen] event:[voltage_2: 237.22], device:[myShelly3EM]
2022.09.15 11:59:55.662 5: [CO2Leverkusen] event:[Zeitstempel: 2022-09-15 11:59:55], device:[myShelly3EM]
2022.09.15 11:59:55.662 5: [CO2Leverkusen] event:[total_power: 0.29452], device:[myShelly3EM]
2022.09.15 11:59:55.706 4: monitoring (mymonitoring) triggered by "myShelly3EM Zeitstempel: 2022-09-15 11:59:55"
2022.09.15 11:59:55.707 5: monitoring (mymonitoring) errorFuncAdd and errorFuncRemove are preset
2022.09.15 11:59:55.707 5: monitoring (mymonitoring) only addRegex is defined
2022.09.15 11:59:55.708 5: monitoring (mymonitoring)
    entering monitoring_modify
        reading:   error
        operation: remove
        value:     myShelly3EM
        at:        now
2022.09.15 11:59:55.710 5: monitoring (mymonitoring)
    entering monitoring_modify
        reading:   error
        operation: add
        value:     myShelly3EM
        at:        2022-09-15 13:59:55
2022.09.15 11:59:55.714 5: monitoring (mymonitoring) warningFuncAdd and warningFuncRemove are preset
2022.09.15 11:59:55.714 5: monitoring (mymonitoring) only addRegex is defined
2022.09.15 11:59:55.715 5: monitoring (mymonitoring)
    entering monitoring_modify
        reading:   warning
        operation: remove
        value:     myShelly3EM
        at:        now
2022.09.15 11:59:55.717 5: monitoring (mymonitoring)
    entering monitoring_modify
        reading:   warning
        operation: add
        value:     myShelly3EM
        at:        2022-09-15 12:59:55
2022.09.15 11:59:55.720 5: End notify loop for myShelly3EM
2022.09.15 11:59:55.724 5: Cmd: >{SIP_rBU('blockCallcenter','state;listen_echo|listen_alive;1322|expire;300')}<
2022.09.15 11:59:55.725 5: blockCallcenter, readingB:state Val:listen_echo
2022.09.15 11:59:55.725 5: blockCallcenter, readingB:listen_alive Val:1322
2022.09.15 11:59:55.725 5: blockCallcenter, readingB:expire Val:300
2022.09.15 11:59:55.726 5: Starting notify loop for blockCallcenter, 3 event(s), first is listen_echo
2022.09.15 11:59:55.726 5: Battery.State: not on any display, ignoring notify
2022.09.15 11:59:55.727 5: [CO2Leverkusen] event:[state: listen_echo], device:[blockCallcenter]
2022.09.15 11:59:55.727 5: [CO2Leverkusen] event:[listen_alive: 1322], device:[blockCallcenter]
2022.09.15 11:59:55.727 5: [CO2Leverkusen] event:[expire: 300], device:[blockCallcenter]
2022.09.15 11:59:55.748 5: End notify loop for blockCallcenter
2022.09.15 11:59:56.536 4: Ignoring unknown_17C40D
2022.09.15 11:59:56.536 4: Ignoring unknown_1AF7F7
2022.09.15 11:59:56.536 4: Ignoring unknown_1B6D68
2022.09.15 11:59:56.536 4: Ignoring unknown_1B6D80
2022.09.15 11:59:56.537 4: Ignoring unknown_1BCFBB
2022.09.15 11:59:56.537 4: Ignoring unknown_1BDE12
2022.09.15 11:59:56.537 4: Ignoring unknown_238DC9
2022.09.15 11:59:56.537 4: Ignoring unknown_2670A4
2022.09.15 11:59:56.538 4: Ignoring unknown_28FDC6
2022.09.15 11:59:56.538 4: Ignoring unknown_2C5E75
2022.09.15 11:59:56.538 4: Ignoring unknown_2CC0E8
2022.09.15 11:59:56.538 4: Ignoring unknown_2FCE06
2022.09.15 11:59:56.538 4: Ignoring unknown_3189C9
2022.09.15 11:59:56.539 4: Ignoring unknown_32626A
2022.09.15 11:59:56.539 4: Ignoring unknown_33E04F
2022.09.15 11:59:56.539 4: Ignoring unknown_351AF0
2022.09.15 11:59:56.539 4: Ignoring unknown_3C64D9
2022.09.15 11:59:56.539 4: Ignoring unknown_3C6FEC
2022.09.15 11:59:56.540 4: Ignoring unknown_3D84DC
2022.09.15 11:59:56.540 4: Ignoring unknown_3FA1E7
2022.09.15 11:59:56.540 4: Ignoring unknown_40F77C
2022.09.15 11:59:56.540 4: Ignoring unknown_419B7A
2022.09.15 11:59:56.540 4: Ignoring unknown_459665
2022.09.15 11:59:56.541 4: Ignoring unknown_4E1876
2022.09.15 11:59:56.541 4: Ignoring unknown_4EBFD1
2022.09.15 11:59:56.541 4: Ignoring unknown_56EDF5
2022.09.15 11:59:56.541 4: Ignoring unknown_596BA5
2022.09.15 11:59:56.541 4: Ignoring unknown_5CB528
2022.09.15 11:59:56.542 4: Ignoring unknown_5DF56B
2022.09.15 11:59:56.542 4: Ignoring unknown_5E1E3C
2022.09.15 11:59:56.542 4: Ignoring unknown_647C26
2022.09.15 11:59:56.542 4: Ignoring unknown_654D39
2022.09.15 11:59:56.542 4: Ignoring unknown_67E73E
2022.09.15 11:59:56.543 4: Ignoring unknown_6842C4
2022.09.15 11:59:56.543 4: Ignoring unknown_6E19CC
2022.09.15 11:59:56.543 4: Ignoring unknown_6F4517
2022.09.15 11:59:56.543 4: Ignoring unknown_71D745
2022.09.15 11:59:56.544 4: Ignoring unknown_75CB39
2022.09.15 11:59:56.544 4: Ignoring unknown_7606DC
2022.09.15 11:59:56.544 4: Ignoring unknown_791B83
2022.09.15 11:59:56.544 4: Ignoring unknown_87CA4E
2022.09.15 11:59:56.544 4: Ignoring unknown_A56D6E
2022.09.15 11:59:56.545 4: Ignoring unknown_ABACF0
2022.09.15 11:59:56.545 4: Ignoring unknown_B0DAAF
2022.09.15 11:59:56.545 4: Ignoring unknown_B153C2
2022.09.15 11:59:56.545 4: Ignoring unknown_B732AE
2022.09.15 11:59:56.545 4: Ignoring unknown_B8A587
2022.09.15 11:59:56.546 4: Ignoring unknown_B8BDF1
2022.09.15 11:59:56.546 4: Ignoring unknown_BB34EA
2022.09.15 11:59:56.546 4: Ignoring unknown_BCED32
2022.09.15 11:59:56.546 4: Ignoring unknown_BF3CC7
2022.09.15 12:00:00.014 5: Starting notify loop for Warmwasser.Zirkulation, 1 event(s), first is cmd_count: 1
2022.09.15 12:00:00.015 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.015 5: [CO2Leverkusen] event:[cmd_count: 1], device:[Warmwasser.Zirkulation]
2022.09.15 12:00:00.054 5: End notify loop for Warmwasser.Zirkulation
2022.09.15 12:00:00.056 5: Cmd: >set Heizung POWER1 on<
2022.09.15 12:00:00.057 5: MQTT MyBroker message sent: Publish/at-most-once cmnd/Heizung/POWER1 
  6f 6e                                            on
2022.09.15 12:00:00.057 5: DevIo_SimpleWrite MyBroker: 30170013636d6e642f4865697a756e672f504f574552316f6e
2022.09.15 12:00:00.065 5: Starting notify loop for Heizung, 3 event(s), first is POWER1: on
2022.09.15 12:00:00.066 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.066 5: [CO2Leverkusen] event:[POWER1: on], device:[Heizung]
2022.09.15 12:00:00.067 5: [CO2Leverkusen] event:[Warmwasserpumpe: <span style='color:#FF0000'><b>läuft</b></span>], device:[Heizung]
2022.09.15 12:00:00.067 5: [CO2Leverkusen] event:[Pumpe: 0], device:[Heizung]
2022.09.15 12:00:00.099 5: End notify loop for Heizung
2022.09.15 12:00:00.102 5: Starting notify loop for Heizung, 1 event(s), first is transmission-state: outgoing publish sent
2022.09.15 12:00:00.102 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.103 5: [CO2Leverkusen] event:[transmission-state: outgoing publish sent], device:[Heizung]
2022.09.15 12:00:00.129 5: End notify loop for Heizung
2022.09.15 12:00:00.130 5: Starting notify loop for Warmwasser.Zirkulation, 4 event(s), first is cmd_nr: 1
2022.09.15 12:00:00.131 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.131 5: [CO2Leverkusen] event:[cmd_nr: 1], device:[Warmwasser.Zirkulation]
2022.09.15 12:00:00.132 5: [CO2Leverkusen] event:[cmd: 1], device:[Warmwasser.Zirkulation]
2022.09.15 12:00:00.132 5: [CO2Leverkusen] event:[cmd_event: timer_11], device:[Warmwasser.Zirkulation]
2022.09.15 12:00:00.132 5: [CO2Leverkusen] event:[state: cmd_1], device:[Warmwasser.Zirkulation]
2022.09.15 12:00:00.185 5: End notify loop for Warmwasser.Zirkulation
2022.09.15 12:00:00.192 4: [GasDE] start request
2022.09.15 12:00:00.193 4: [GasDE] next request: 2022.09.15 15:00:00
2022.09.15 12:00:00.194 5: HttpUtils url=<hidden> NonBlocking via https
2022.09.15 12:00:00.195 5: DNS QUERY 7072010000010000000000000461677369036769650265750000010001
2022.09.15 12:00:00.211 5: Starting notify loop for Haushaltsraum.Lueftung, 1 event(s), first is cmd_count: 1
2022.09.15 12:00:00.211 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.212 5: [CO2Leverkusen] event:[cmd_count: 1], device:[Haushaltsraum.Lueftung]
2022.09.15 12:00:00.234 5: End notify loop for Haushaltsraum.Lueftung
2022.09.15 12:00:00.235 5: Cmd: >set HWR.Ventilator_ESP_Router command gpio 14 set low<
2022.09.15 12:00:00.236 5: MQTT MyBroker message sent: Publish/at-most-once /WiFi/ESPRouter_5f7345/command 
  67 70 69 6f 20 31 34 20 73 65 74 20 6c 6f 77     gpio 14 set low
2022.09.15 12:00:00.236 5: DevIo_SimpleWrite MyBroker: 302f001e2f576946692f455350526f757465725f3566373334352f636f6d6d616e646770696f20313420736574206c6f77
2022.09.15 12:00:00.241 5: Starting notify loop for HWR.Ventilator_ESP_Router, 2 event(s), first is transmission-state: outgoing publish sent
2022.09.15 12:00:00.242 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.242 5: [CO2Leverkusen] event:[transmission-state: outgoing publish sent], device:[HWR.Ventilator_ESP_Router]
2022.09.15 12:00:00.242 5: [CO2Leverkusen] event:[Zeitstempel: 2022-09-15 12:00], device:[HWR.Ventilator_ESP_Router]
2022.09.15 12:00:00.264 4: monitoring (mymonitoring) triggered by "HWR.Ventilator_ESP_Router Zeitstempel: 2022-09-15 12:00"
2022.09.15 12:00:00.265 5: monitoring (mymonitoring) errorFuncAdd and errorFuncRemove are preset
2022.09.15 12:00:00.265 5: monitoring (mymonitoring) only addRegex is defined
2022.09.15 12:00:00.266 5: monitoring (mymonitoring)
    entering monitoring_modify
        reading:   error
        operation: remove
        value:     HWR.Ventilator_ESP_Router
        at:        now
2022.09.15 12:00:00.267 5: monitoring (mymonitoring)
    entering monitoring_modify
        reading:   error
        operation: add
        value:     HWR.Ventilator_ESP_Router
        at:        2022-09-15 14:00:00
2022.09.15 12:00:00.269 5: monitoring (mymonitoring) warningFuncAdd and warningFuncRemove are preset
2022.09.15 12:00:00.269 5: monitoring (mymonitoring) only addRegex is defined
2022.09.15 12:00:00.270 5: monitoring (mymonitoring)
    entering monitoring_modify
        reading:   warning
        operation: remove
        value:     HWR.Ventilator_ESP_Router
        at:        now
2022.09.15 12:00:00.271 5: monitoring (mymonitoring)
    entering monitoring_modify
        reading:   warning
        operation: add
        value:     HWR.Ventilator_ESP_Router
        at:        2022-09-15 13:00:00
2022.09.15 12:00:00.273 5: End notify loop for HWR.Ventilator_ESP_Router
2022.09.15 12:00:00.274 5: Starting notify loop for Haushaltsraum.Lueftung, 4 event(s), first is cmd_nr: 3
2022.09.15 12:00:00.274 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.275 5: [CO2Leverkusen] event:[cmd_nr: 3], device:[Haushaltsraum.Lueftung]
2022.09.15 12:00:00.275 5: [CO2Leverkusen] event:[cmd: 3], device:[Haushaltsraum.Lueftung]
2022.09.15 12:00:00.275 5: [CO2Leverkusen] event:[cmd_event: timer_5], device:[Haushaltsraum.Lueftung]
2022.09.15 12:00:00.275 5: [CO2Leverkusen] event:[state: cmd_3], device:[Haushaltsraum.Lueftung]
2022.09.15 12:00:00.297 5: End notify loop for Haushaltsraum.Lueftung
2022.09.15 12:00:00.298 5: Starting notify loop for Haushaltsraum.Lueftung, 1 event(s), first is wait_timer: 15.09.2022 13:00:00 cmd_3 timer_5
2022.09.15 12:00:00.299 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.299 5: [CO2Leverkusen] event:[wait_timer: 15.09.2022 13:00:00 cmd_3 timer_5], device:[Haushaltsraum.Lueftung]
2022.09.15 12:00:00.319 5: End notify loop for Haushaltsraum.Lueftung
2022.09.15 12:00:00.323 5: DNS QUERY 707201000001000000000000097365727669636573370661726367697303636f6d0000010001
2022.09.15 12:00:00.323 4: [RKI7] start request
2022.09.15 12:00:00.324 4: [RKI7] next request: 2022.09.15 13:00:00
2022.09.15 12:00:00.325 5: HttpUtils url=https://services7.arcgis.com/mOBPykOjAyBO2ZKk/arcgis/rest/services/RKI_Landkreisdaten/FeatureServer/0/query?where=1%3D1&outFields=last_update,cases7_per_100k,BEZ,BEM,GEN,BL,county&returnGeometry=false&outSR=4326&f=json NonBlocking via https
2022.09.15 12:00:00.326 5: DNS QUERY 707201000001000000000000097365727669636573370661726367697303636f6d0000010001
2022.09.15 12:00:00.327 4: [coronaSpreadNRW] start request
2022.09.15 12:00:00.328 4: [coronaSpreadNRW] next request: 2022.09.15 13:00:00
2022.09.15 12:00:00.328 5: HttpUtils url=https://services7.arcgis.com/mOBPykOjAyBO2ZKk/arcgis/rest/services/Coronaf%C3%A4lle_in_den_Bundesl%C3%A4ndern/FeatureServer/0/query?where=1%3D1&outFields=LAN_ew_GEN,Fallzahl,Aktualisierung,faelle_100000_EW,cases7_bl_per_100k,Death&returnGeometry=false&outSR=4326&f=json NonBlocking via https
2022.09.15 12:00:00.329 5: DNS QUERY 707201000001000000000000097365727669636573370661726367697303636f6d0000010001
2022.09.15 12:00:00.353 4: BlockingCall (TRAFFIC_DoUpdate): created child (36728), uses telnetForBlockingFn_1663169046.15034 to connect back
2022.09.15 12:00:00.389 5: Starting notify loop for Ueberwachung, 10 event(s), first is cmd_nr: 2
2022.09.15 12:00:00.390 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.392 5: [CO2Leverkusen] event:[cmd_nr: 2], device:[Ueberwachung]
2022.09.15 12:00:00.392 5: [CO2Leverkusen] event:[cmd: 2], device:[Ueberwachung]
2022.09.15 12:00:00.392 5: [CO2Leverkusen] event:[cmd_event: timer_1], device:[Ueberwachung]
2022.09.15 12:00:00.392 5: [CO2Leverkusen] event:[state: cmd_2], device:[Ueberwachung]
2022.09.15 12:00:00.393 5: [CO2Leverkusen] event:[UeberwachungEG: 9.30], device:[Ueberwachung]
2022.09.15 12:00:00.393 5: [CO2Leverkusen] event:[ErfolgEG: 8.08], device:[Ueberwachung]
2022.09.15 12:00:00.393 5: [CO2Leverkusen] event:[UeberwachungOG: 21.45], device:[Ueberwachung]
2022.09.15 12:00:00.393 5: [CO2Leverkusen] event:[ErfolgOG: 19.73], device:[Ueberwachung]
2022.09.15 12:00:00.393 5: [CO2Leverkusen] event:[Sum_Ueberwachung: 30.75], device:[Ueberwachung]
2022.09.15 12:00:00.393 5: [CO2Leverkusen] event:[Erfolg: 27.81], device:[Ueberwachung]
2022.09.15 12:00:00.437 5: End notify loop for Ueberwachung
2022.09.15 12:00:00.465 5: Starting notify loop for CO2Leverkusen, 3 event(s), first is 74
2022.09.15 12:00:00.466 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.466 5: [CO2Leverkusen] event:[state: 74], device:[CO2Leverkusen]
2022.09.15 12:00:00.466 5: [CO2Leverkusen] event:[oeko_co2: 21], device:[CO2Leverkusen]
2022.09.15 12:00:00.466 5: [CO2Leverkusen] event:[standard_co2: 255], device:[CO2Leverkusen]
2022.09.15 12:00:00.490 5: End notify loop for CO2Leverkusen
2022.09.15 12:00:00.499 5: rhasspyMQTT2: dispatch autocreate=no\000rhasspyMQTT2\000cmnd/Heizung/POWER1\000on
2022.09.15 12:00:00.502 5: DNS ANSWER 145:707281800001000500000000097365727669636573370661726367697303636f6d0000010001c00c000500010000012c001f0e643138716b62366c7675356e666a0a636c6f756466726f6e74036e657400c032000100010000003c00041242f864c032000100010000003c00041242f82ac032000100010000003c00041242f86cc032000100010000003c00041242f82b
2022.09.15 12:00:00.503 4: DNS result for services7.arcgis.com: 18.66.248.100, ttl:60
2022.09.15 12:00:00.503 4: IP: services7.arcgis.com -> 18.66.248.100
2022.09.15 12:00:00.505 5: DNS ANSWER 61:7072818000010002000000000461677369036769650265750000010001c00c000100010000012c00046812185ac00c000100010000012c00046812195a
2022.09.15 12:00:00.506 4: DNS result for agsi.gie.eu: 104.18.24.90, ttl:300
2022.09.15 12:00:00.506 4: IP: agsi.gie.eu -> 104.18.24.90
2022.09.15 12:00:00.508 5: DNS ANSWER 145:707281800001000500000000097365727669636573370661726367697303636f6d0000010001c00c000500010000012c001f0e643138716b62366c7675356e666a0a636c6f756466726f6e74036e657400c032000100010000003c00041242f864c032000100010000003c00041242f82ac032000100010000003c00041242f86cc032000100010000003c00041242f82b
2022.09.15 12:00:00.509 4: DNS result for services7.arcgis.com: 18.66.248.100, ttl:60
2022.09.15 12:00:00.509 4: IP: services7.arcgis.com -> 18.66.248.100
2022.09.15 12:00:00.511 5: MQTT MyBroker message received: Publish/at-most-once cmnd/Heizung/POWER1 
  6f 6e                                            on
2022.09.15 12:00:00.514 5: publish received for cmnd/Heizung/POWER1, on
2022.09.15 12:00:00.519 5: Starting notify loop for Heizung, 1 event(s), first is transmission-state: incoming publish received
2022.09.15 12:00:00.520 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.520 5: [CO2Leverkusen] event:[transmission-state: incoming publish received], device:[Heizung]
2022.09.15 12:00:00.543 5: End notify loop for Heizung
2022.09.15 12:00:00.543 5: calling readingsSingleUpdate(Heizung,POWER1,on,1)
2022.09.15 12:00:00.553 5: MQTT MyBroker message received: Publish/at-most-once stat/Heizung/RESULT 
  7b 22 50 4f 57 45 52 31 22 3a 22 4f 4e 22 7d     {"POWER1":"ON"}
2022.09.15 12:00:00.556 5: publish received for stat/Heizung/RESULT, {"POWER1":"ON"}
2022.09.15 12:00:00.559 5: calling readingsSingleUpdate(Heizung,RESULT,{"POWER1":"ON"},1)
2022.09.15 12:00:00.561 5: Starting notify loop for Heizung, 1 event(s), first is RESULT: {"POWER1":"ON"}
2022.09.15 12:00:00.562 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.562 5: [CO2Leverkusen] event:[RESULT: {"POWER1":"ON"}], device:[Heizung]
2022.09.15 12:00:00.583 5: End notify loop for Heizung
2022.09.15 12:00:00.589 5: MQTT MyBroker message received: Publish/at-most-once stat/Heizung/POWER1 
  4f 4e                                            ON
2022.09.15 12:00:00.592 5: publish received for stat/Heizung/POWER1, ON
2022.09.15 12:00:00.595 5: calling readingsSingleUpdate(Heizung,POWER1,ON,1)
2022.09.15 12:00:00.598 5: Starting notify loop for Heizung, 1 event(s), first is POWER1: ON
2022.09.15 12:00:00.598 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.599 5: [CO2Leverkusen] event:[POWER1: ON], device:[Heizung]
2022.09.15 12:00:00.612 5: HourCounter HourCount.Warmwasser Run.577 value:1 changedTimestamp:2022-09-15 11:55:00
2022.09.15 12:00:00.613 4: HourCounter HourCount.Warmwasser Run.657 rising edge; pauseTimeIncr:3269 countPerDay:12
2022.09.15 12:00:00.615 5: Starting notify loop for HourCount.Warmwasser, 10 event(s), first is countsPerDay: 12
2022.09.15 12:00:00.615 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.616 5: [CO2Leverkusen] event:[countsPerDay: 12], device:[HourCount.Warmwasser]
2022.09.15 12:00:00.616 5: [CO2Leverkusen] event:[countsOverall: 29766], device:[HourCount.Warmwasser]
2022.09.15 12:00:00.616 5: [CO2Leverkusen] event:[pulseTimeIncrement: 0], device:[HourCount.Warmwasser]
2022.09.15 12:00:00.616 5: [CO2Leverkusen] event:[pauseTimeIncrement: 3269], device:[HourCount.Warmwasser]
2022.09.15 12:00:00.617 5: [CO2Leverkusen] event:[pauseTimeEdge: 3269], device:[HourCount.Warmwasser]
2022.09.15 12:00:00.617 5: [CO2Leverkusen] event:[pauseTimePerDay: 40081], device:[HourCount.Warmwasser]
2022.09.15 12:00:00.617 5: [CO2Leverkusen] event:[pauseTimeOverall: 90874169], device:[HourCount.Warmwasser]
2022.09.15 12:00:00.617 5: [CO2Leverkusen] event:[value: 1], device:[HourCount.Warmwasser]
2022.09.15 12:00:00.617 5: [CO2Leverkusen] event:[state: 12], device:[HourCount.Warmwasser]
2022.09.15 12:00:00.617 5: [CO2Leverkusen] event:[tickUpdated: 222], device:[HourCount.Warmwasser]
2022.09.15 12:00:00.645 5: End notify loop for HourCount.Warmwasser
2022.09.15 12:00:00.646 5: Starting notify loop for HourCount.Warmwasser, 1 event(s), first is tickChanged: 598
2022.09.15 12:00:00.647 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.647 5: [CO2Leverkusen] event:[tickChanged: 598], device:[HourCount.Warmwasser]
2022.09.15 12:00:00.667 5: End notify loop for HourCount.Warmwasser
2022.09.15 12:00:00.667 4: HourCounter HourCount.Warmwasser Run.729 tickChanged fired 
2022.09.15 12:00:00.668 5: Starting notify loop for HourCount.Warmwasser, 1 event(s), first is tickHour: 19
2022.09.15 12:00:00.668 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.669 5: [CO2Leverkusen] event:[tickHour: 19], device:[HourCount.Warmwasser]
2022.09.15 12:00:00.689 5: End notify loop for HourCount.Warmwasser
2022.09.15 12:00:00.689 4: HourCounter HourCount.Warmwasser Run.738 tickHour fired
2022.09.15 12:00:00.689 4: HourCounter HourCount.Warmwasser ExecQueue.130 cnt: -1
2022.09.15 12:00:00.689 5: HourCounter HourCount.Warmwasser Run.801 nextCall:300 changedTimestamp:2022-09-15 12:00:00
2022.09.15 12:00:00.699 5: End notify loop for Heizung
2022.09.15 12:00:00.705 5: MQTT MyBroker message received: Publish/at-most-once /WiFi/ESPRouter_5f7345/system/response 
  53 75 63 63 65 73 73 66 75 6c 6c 79 20 65 78 65  Successfully exe
  63 75 74 65 64 20 31 34 20 73 65 74 20 6c 6f 77  cuted 14 set low
  0d 0a                                            ..
2022.09.15 12:00:00.708 5: publish received for /WiFi/ESPRouter_5f7345/system/response, Successfully executed 14 set low

2022.09.15 12:00:00.711 5: Starting notify loop for HWR.Ventilator_ESP_Router, 1 event(s), first is transmission-state: incoming publish received
2022.09.15 12:00:00.711 5: Battery.State: not on any display, ignoring notify
2022.09.15 12:00:00.712 5: [CO2Leverkusen] event:[transmission-state: incoming publish received], device:[HWR.Ventilator_ESP_Router]
2022.09.15 12:00:00.732 5: End notify loop for HWR.Ventilator_ESP_Router
2022.09.15 12:00:00.732 5: calling readingsSingleUpdate(HWR.Ventilator_ESP_Router,response,Successfully executed 14 set low
,1)
2022.09.15 12:00:00.742 5: DNS ANSWER 145:707281800001000500000000097365727669636573370661726367697303636f6d0000010001c00c000500010000012c001f0e643138716b62366c7675356e666a0a636c6f756466726f6e74036e657400c032000100010000003c00041242f864c032000100010000003c00041242f82ac032000100010000003c00041242f86cc032000100010000003c00041242f82b
2022.09.15 12:00:00.742 4: DNS result for services7.arcgis.com: 18.66.248.100, ttl:60
2022.09.15 12:00:00.745 4: Connection accepted from telnetForBlockingFn_1663169046.15034_127.0.0.1_36938
2022.09.15 12:00:00.746 5: rhasspyMQTT2: dispatch autocreate=no\000rhasspyMQTT2\000stat/Heizung/RESULT\000{"POWER1":"ON"}
2022.09.15 12:00:00.747 5: rhasspyMQTT2: dispatch autocreate=no\000rhasspyMQTT2\000stat/Heizung/POWER1\000ON
2022.09.15 12:00:00.748 5: rhasspyMQTT2: dispatch autocreate=no\000rhasspyMQTT2\000/WiFi/ESPRouter_5f7345/command\000gpio 14 set low
2022.09.15 12:00:00.749 5: rhasspyMQTT2: dispatch autocreate=no\000rhasspyMQTT2\000/WiFi/ESPRouter_5f7345/system/response\000Successfully executed 14 set low\r\n
2022.09.15 12:00:00.769 5: Cmd: >{BlockingRegisterTelnet($cl,4348)}<
2022.09.15 12:00:00.797 5: HttpUtils request header:
GET /mOBPykOjAyBO2ZKk/arcgis/rest/services/Coronaf%C3%A4lle_in_den_Bundesl%C3%A4ndern/FeatureServer/0/query?where=1%3D1&outFields=LAN_ew_GEN,Fallzahl,Aktualisierung,faelle_100000_EW,cases7_bl_per_100k,Death&returnGeometry=false&outSR=4326&f=json HTTP/1.0
Host: services7.arcgis.com
User-Agent: fhem
Accept-Encoding: gzip,deflate

Accept: application/json
Accept-Charset: utf-8, iso-8859-1

2022.09.15 12:00:00.801 5: HttpUtils request header:
GET /mOBPykOjAyBO2ZKk/arcgis/rest/services/RKI_Landkreisdaten/FeatureServer/0/query?where=1%3D1&outFields=last_update,cases7_per_100k,BEZ,BEM,GEN,BL,county&returnGeometry=false&outSR=4326&f=json HTTP/1.0
Host: services7.arcgis.com
User-Agent: fhem
Accept-Encoding: gzip,deflate

Accept: application/json
Accept-Charset: utf-8, iso-8859-1

2022.09.15 12:00:00.808 5: HttpUtils request header:
GET /api?country=de&from=2022-03-01&size=300 be304ce79b2051f6d99f7d078e6b954e HTTP/1.0
Host: agsi.gie.eu
User-Agent: fhem
Accept-Encoding: gzip,deflate
x-key: be304ce79b2051f6d99f7d078e6b954e
Accept: application/json
Accept-Charset: utf-8, iso-8859-1

2022.09.15 12:00:00.819 4: https://services7.arcgis.com/mOBPykOjAyBO2ZKk/arcgis/rest/services/Coronaf%C3%A4lle_in_den_Bundesl%C3%A4ndern/FeatureServer/0/query?where=1%3D1&outFields=LAN_ew_GEN,Fallzahl,Aktualisierung,faelle_100000_EW,cases7_bl_per_100k,Death&returnGeometry=false&outSR=4326&f=json: HTTP response code 200
2022.09.15 12:00:00.819 5: HttpUtils https://services7.arcgis.com/mOBPykOjAyBO2ZKk/arcgis/rest/services/Coronaf%C3%A4lle_in_den_Bundesl%C3%A4ndern/FeatureServer/0/query?where=1%3D1&outFields=LAN_ew_GEN,Fallzahl,Aktualisierung,faelle_100000_EW,cases7_bl_per_100k,Death&returnGeometry=false&outSR=4326&f=json: Got data, length: 4018
2022.09.15 12:00:00.820 5: HttpUtils response header:
HTTP/1.1 200 OK
Content-Type: application/json; charset=utf-8
Content-Length: 4018
Connection: close
Cache-Control: public, max-age=3600, s-maxage=3600
Last-Modified: Thu, 15 Sep 2022 01:10:36 GMT
ETag: sd2_272324887
X-ArcGIS-Trace-Id: 320155f7cdf101153bab1e9d57bc6fc5
x-esri-query-readonly-replica: true
x-esri-query-request-units: 2
x-esri-tiles-basic-query-mode: true
x-esri-tiles-basic-query-type: Basic
x-esri-org-request-units-per-min: usage=4;max=28800
X-ArcGIS-Correlation-Id: 00-320155f7cdf101153bab1e9d57bc6fc5-5cbbcff23a1ad9d4-00
X-ArcGIS-Instance: fkjr5dqbc000000
Strict-Transport-Security: max-age=63072000
Access-Control-Allow-Origin: *
Date: Thu, 15 Sep 2022 09:21:00 GMT
X-Cache: Hit from cloudfront
Via: 1.1 809c299e67c4ffca3db95351c7287bd8.cloudfront.net (CloudFront)
X-Amz-Cf-Pop: DUS51-P1
X-Amz-Cf-Id: c38hAkBAQ83ny6or6L2URfREkQPl2_sIyHFRAb_iwmH8diiVINY2yQ==
Age: 2340
2022.09.15 12:00:00.821 4: [coronaSpreadNRW] api encoding is utf-8, designated encoder is utf-8-strict
2022.09.15 12:01:32.756 1: Including fhem.cfg
2022.09.15 12:01:33.196 3: WEB: port 8083 opened
2022.09.15 12:01:33.266 3: WEBphone: port 8084 opened
2022.09.15 12:01:33.276 3: WEBtablet: port 8085 opened
2022.09.15 12:01:33.298 3: httpWEB: port 8086 opened
2022.09.15 12:01:33.314 3: telnetPort: port 7072 opened

Der syslog:

Code Auswählen

Sep 15 12:00:00 HPT610 kernel: [67040.482424] show_signal: 1 callbacks suppressed
Sep 15 12:00:00 HPT610 kernel: [67040.482437] traps: perl[1000] trap stack segment ip:55c44a74d8e4 sp:7ffcbc208800 error:0 in perl[55c44a713000+185000]
Sep 15 12:00:00 HPT610 systemd[1]: fhem.service: Main process exited, code=killed, status=7/BUS
Sep 15 12:00:01 HPT610 CRON[36730]: (root) CMD (   PATH="$PATH:/usr/sbin:/usr/local/bin/" pihole updatechecker local)
Sep 15 12:01:31 HPT610 systemd[1]: fhem.service: State 'stop-sigterm' timed out. Killing.
Sep 15 12:01:31 HPT610 systemd[1]: fhem.service: Killing process 1322 (perl) with signal SIGKILL.
Sep 15 12:01:31 HPT610 systemd[1]: fhem.service: Failed with result 'signal'.
Sep 15 12:01:31 HPT610 systemd[1]: fhem.service: Consumed 2h 8min 37.127s CPU time.
Sep 15 12:01:31 HPT610 systemd[1]: fhem.service: Scheduled restart job, restart counter is at 1.
Sep 15 12:01:31 HPT610 systemd[1]: Stopped FHEM Home Automation.
Sep 15 12:01:31 HPT610 systemd[1]: fhem.service: Consumed 2h 8min 37.127s CPU time.
Sep 15 12:01:31 HPT610 systemd[1]: Starting FHEM Home Automation...
Sep 15 12:01:32 HPT610 systemd[1]: Started FHEM Home Automation.

Leider ist die RAM-Belegung jetzt wieder bei 70%.

Viele Grüße Gisbert

KölnSolar · 15 September 2022, 12:38:37

Das sieht doch nach

Code Auswählen

2022.09.15 12:00:00.821 4: [coronaSpreadNRW] api encoding is utf-8, designated encoder is utf-8-strictals Grund(loop, sleep ?)aus. Oder dem, was stündlich danach kommt.
Grüße Markus

Beta-User · 15 September 2022, 12:44:18

Vielleicht zeigst du mal ein list von dem Ding...

Gisbert · 15 September 2022, 12:51:22

Nicht, dass ich seit ca. 2 Wochen Corona (mittlerweile wieder besser, aber körperlich immer noch platt) habe, jetzt schlägt dieser Virus auch noch in Fhem zu.

raw-Definition:

Code Auswählen

defmod coronaSpreadNRW JsonMod https://services7.arcgis.com/mOBPykOjAyBO2ZKk/arcgis/rest/services/Coronaf%C3%A4lle_in_den_Bundesl%C3%A4ndern/FeatureServer/0/query?where=1%3D1&outFields=LAN_ew_GEN,Fallzahl,Aktualisierung,faelle_100000_EW,cases7_bl_per_100k,Death&returnGeometry=false&outSR=4326&f=json
attr coronaSpreadNRW alias coronaSpread NRW
attr coronaSpreadNRW comment https://forum.fhem.de/index.php/topic,109413.msg1093720.html#msg1093720\
https://forum.fhem.de/index.php/topic,109413.msg1093770.html#msg1093770\
Ausrichtung wurde entfernt:\
my $ret = "<div style=\"text-align: left;;\">";;\
...\
$ret .= "</div>";;
attr coronaSpreadNRW event-on-update-reading .computedReadings
attr coronaSpreadNRW icon coronavirus
attr coronaSpreadNRW readingList multi(jsonPath("\$.features[?(\@.attributes.LAN_ew_GEN in ['Nordrhein-Westfalen'])]"), concat(count(),'_name'), property('attributes.LAN_ew_GEN'));;\
multi(jsonPath("\$.features[?(\@.attributes.LAN_ew_GEN in ['Nordrhein-Westfalen'])]"), concat(count(),'_cases'), property('attributes.Fallzahl'));;\
multi(jsonPath("\$.features[?(\@.attributes.LAN_ew_GEN in ['Nordrhein-Westfalen'])]"), concat(count(),'_deaths'), property('attributes.Death'));;\
multi(jsonPath("\$.features[?(\@.attributes.LAN_ew_GEN in ['Nordrhein-Westfalen'])]"), concat(count(),'_lastUpdate'), property('attributes.Aktualisierung'));;\
multi(jsonPath("\$.features[?(\@.attributes.LAN_ew_GEN in ['Nordrhein-Westfalen'])]"), concat(count(),'_RKI7'), property('attributes.cases7_bl_per_100k'));;
attr coronaSpreadNRW room Corona
attr coronaSpreadNRW sortby 3
attr coronaSpreadNRW stateFormat {my $ret .= "last RKI CoViD-19 check</br>on ";;\
 $ret .= POSIX::strftime("%d.%m. %H:%M",localtime(time_str2num(ReadingsTimestamp($name,".computedReadings","2000-01-01 00:00:00"))))."</br>";;\
# $ret .= ReadingsVal($name,"0_name","-").":</br>";;\
 $ret .= "RKI7: <font style=\"color:#147bff;;\">".round(ReadingsVal($name,"0_RKI7","-"),1)."</font></br>";;\
 $ret .= "F&auml;;lle: <font style=\"color:#147bff;;\">".ReadingsVal($name,"0_cases","-")."</font></br>";;\
 $ret .= "Tote: <font style=\"color:#147bff;;\">".ReadingsVal($name,"0_deaths","-")."</font>";;\
 return $ret;;}
attr coronaSpreadNRW userReadings 0_lastUpdatedh {POSIX::strftime ("%d.%m. %H:%M", localtime(ReadingsVal($name,"0_lastUpdate",0)/1000));;}, 1_lastUpdatedh {POSIX::strftime ("%d.%m. %H:%M", localtime(ReadingsVal($name,"1_lastUpdate",0)/1000));;}

Ein list:

Code Auswählen

Internals:
   CFGFN      ./FHEM/Corona.cfg
   DEF        https://services7.arcgis.com/mOBPykOjAyBO2ZKk/arcgis/rest/services/Coronaf%C3%A4lle_in_den_Bundesl%C3%A4ndern/FeatureServer/0/query?where=1%3D1&outFields=LAN_ew_GEN,Fallzahl,Aktualisierung,faelle_100000_EW,cases7_bl_per_100k,Death&returnGeometry=false&outSR=4326&f=json
   FUUID      5f8d5ff4-f33f-e986-f2ce-70433f69c3df99bd
   NAME       coronaSpreadNRW
   NEXT       2022-09-15 13:00:00
   NR         1209
   STATE      last RKI CoViD-19 check</br>on 15.09. 11:00</br>RKI7: <font style="color:#147bff;">222.7</font></br>F&auml;lle: <font style="color:#147bff;">6690988</font></br>Tote: <font style="color:#147bff;">27230</font>
   SVN        24783 2021-07-21 22:37:12 UTC
   TYPE       JsonMod
   CONFIG:
     IN_REQUEST 0
     SOURCE     https://services7.arcgis.com/mOBPykOjAyBO2ZKk/arcgis/rest/services/Coronaf%C3%A4lle_in_den_Bundesl%C3%A4ndern/FeatureServer/0/query?where=1%3D1&outFields=LAN_ew_GEN,Fallzahl,Aktualisierung,faelle_100000_EW,cases7_bl_per_100k,Death&returnGeometry=false&outSR=4326&f=json
     SECRET:
   READINGS:
     2022-09-15 11:00:01   0_RKI7          222.665164901311
     2022-09-15 11:00:01   0_cases         6690988
     2022-09-15 11:00:01   0_deaths        27230
     2022-09-15 11:00:01   0_lastUpdate    1663192800000
     2022-09-15 11:00:01   0_lastUpdatedh  15.09. 00:00
     2022-09-15 11:00:01   0_name          Nordrhein-Westfalen
     2022-09-15 11:00:01   1_lastUpdatedh  01.01. 01:00
   helper:
     bm:
       JsonMod_Set:
         cnt        3
         dmx        -1000
         dtot       0
         dtotcnt    0
         mTS        15.09. 12:46:28
         max        0.000174045562744141
         tot        0.000291109085083008
         mAr:
           HASH(0x555a3f609e20)
           coronaSpreadNRW
           ?
Attributes:
   alias      coronaSpread NRW
   comment    https://forum.fhem.de/index.php/topic,109413.msg1093720.html#msg1093720
https://forum.fhem.de/index.php/topic,109413.msg1093770.html#msg1093770
Ausrichtung wurde entfernt:
my $ret = "<div style=\"text-align: left;\">";
...
$ret .= "</div>";
   event-on-update-reading .computedReadings
   icon       coronavirus
   readingList multi(jsonPath("\$.features[?(\@.attributes.LAN_ew_GEN in ['Nordrhein-Westfalen'])]"), concat(count(),'_name'), property('attributes.LAN_ew_GEN'));
multi(jsonPath("\$.features[?(\@.attributes.LAN_ew_GEN in ['Nordrhein-Westfalen'])]"), concat(count(),'_cases'), property('attributes.Fallzahl'));
multi(jsonPath("\$.features[?(\@.attributes.LAN_ew_GEN in ['Nordrhein-Westfalen'])]"), concat(count(),'_deaths'), property('attributes.Death'));
multi(jsonPath("\$.features[?(\@.attributes.LAN_ew_GEN in ['Nordrhein-Westfalen'])]"), concat(count(),'_lastUpdate'), property('attributes.Aktualisierung'));
multi(jsonPath("\$.features[?(\@.attributes.LAN_ew_GEN in ['Nordrhein-Westfalen'])]"), concat(count(),'_RKI7'), property('attributes.cases7_bl_per_100k'));
   room       Corona
   sortby     3
   stateFormat {my $ret .= "last RKI CoViD-19 check</br>on ";
 $ret .= POSIX::strftime("%d.%m. %H:%M",localtime(time_str2num(ReadingsTimestamp($name,".computedReadings","2000-01-01 00:00:00"))))."</br>";
# $ret .= ReadingsVal($name,"0_name","-").":</br>";
 $ret .= "RKI7: <font style=\"color:#147bff;\">".round(ReadingsVal($name,"0_RKI7","-"),1)."</font></br>";
 $ret .= "F&auml;lle: <font style=\"color:#147bff;\">".ReadingsVal($name,"0_cases","-")."</font></br>";
 $ret .= "Tote: <font style=\"color:#147bff;\">".ReadingsVal($name,"0_deaths","-")."</font>";
 return $ret;}
   userReadings 0_lastUpdatedh {POSIX::strftime ("%d.%m. %H:%M", localtime(ReadingsVal($name,"0_lastUpdate",0)/1000));}, 1_lastUpdatedh {POSIX::strftime ("%d.%m. %H:%M", localtime(ReadingsVal($name,"1_lastUpdate",0)/1000));}

Viele Grüße Gisbert

frank · 15 September 2022, 13:32:11

wenn ich die url aus dem log im browser eingebe:

Code Auswählen

2022.09.15 12:00:00.819 5: HttpUtils https://services7.arcgis.com/mOBPykOjAyBO2ZKk/arcgis/rest/services/Coronaf%C3%A4lle_in_den_Bundesl%C3%A4ndern/FeatureServer/0/query?where=1%3D1&outFields=LAN_ew_GEN,Fallzahl,Aktualisierung,faelle_100000_EW,cases7_bl_per_100k,Death&returnGeometry=false&outSR=4326&f=json: Got data, length: 4018

erhalte ich eben weniger daten mit "content-length 1131", also nur etwa 1/4 von den 4018.

ist eventuell nur manchmal der json "defekt"?

@gisbert
wie gross war denn der content-length die stunden vor dem crash?

Gisbert · 15 September 2022, 13:46:52

Bei den anderen Abfragen heute (vor und nach dem crash) war diese content-length immer bei 4018.

yersinia · 15 September 2022, 14:16:49

Code Auswählen

Sep 15 12:00:00 HPT610 kernel: [67040.482437] traps: perl[1000] trap stack segment ip:55c44a74d8e4 sp:7ffcbc208800 error:0 in perl[55c44a713000+185000]
Sep 15 12:00:00 HPT610 systemd[1]: fhem.service: Main process exited, code=killed, status=7/BUS
Sep 15 12:01:31 HPT610 systemd[1]: fhem.service: State 'stop-sigterm' timed out. Killing.
Sep 15 12:01:31 HPT610 systemd[1]: fhem.service: Killing process 1322 (perl) with signal SIGKILL.
Sep 15 12:01:31 HPT610 systemd[1]: fhem.service: Failed with result 'signal'.
Sep 15 12:01:31 HPT610 systemd[1]: fhem.service: Consumed 2h 8min 37.127s CPU time.
Sep 15 12:01:31 HPT610 systemd[1]: fhem.service: Scheduled restart job, restart counter is at 1.

Dein Fehlerbild kommt mir bekannt vor: [fhem.pl][98_JsonMod.pm?] Main process exited, code=killed, status=11. Anscheinend auch ähnliche (gleiche?) Quelle und Verwendung.

Meine Laienhafte (!) Vermutung (!) ist, dass JsonMod unter bestimmten Perl-Versionen mit bestimmten (fehlehaften?) Json-Quellen zu Speicheradressierungsfehler o.ä. führt.

Ich hatte damals keine Lösung gefunden/erhalten. Seitdem das JsonMod Device nicht mehr existiert ist auch Ruhe. Laut herrmannj kannst du es ja ggf mit einer neueren perl-Version via cpanel probieren.

Für einen dauerhaften stabilen (produktiven) FHEM-Betrieb würde ich kurzfristig das JsonMod-Device coronaSpreadNRW deaktivieren.

frank · 15 September 2022, 14:24:32

ich würde das device disabeln/löschen, um zu sehen, ob die crashes aufhören.
wenn der content unabhängig vom crash immer gleich gross ist, müsste man den inhalt des übertragenen content vielleicht mal vergleichen.