Test Adapter History 2.0.0

[apollon77]

@homoran sagte in Test Adapter History 2.0.0:

Muss man da noch einen Zauberspruch aufsagen, oder klappt das einfach nur nicht?

Was meinst du? Du musst da auch "löschen" klicken ... dann wird es gelöscht ... oder check die Checkbox dann fragt er ne weile nicht mehr nach

[Homoran]

@apollon77 sagte in Test Adapter History 2.0.0:

Du musst da auch "löschen" klicken ... dann wird es gelöscht

eben nicht

[apollon77]

@homoran debug log vom Adapter mal an und bitte log von so nem löschen zeigen. Dran hab ich nix geändert. Muss man schauen

[Homoran]

@apollon77 Hab ich (oder du) ein Problem.
Das debug müllt das log zu.
ich erkenne nicht was zum löschen gehört

Wie lange dauert das Ganze?

fängt an mit:

2022-04-27 14:53:18.765 - debug: history.0 (4085778) Redis Objects: Use Redis connection: 0.0.0.0:9001
2022-04-27 14:53:18.794 - debug: history.0 (4085778) Objects client ready ... initialize now
2022-04-27 14:53:18.799 - debug: history.0 (4085778) Objects create System PubSub Client
2022-04-27 14:53:18.800 - debug: history.0 (4085778) Objects create User PubSub Client
2022-04-27 14:53:18.801 - debug: history.0 (4085778) Objects client initialize lua scripts
2022-04-27 14:53:18.816 - debug: history.0 (4085778) Objects connected to redis: 0.0.0.0:9001
2022-04-27 14:53:18.822 - debug: history.0 (4085778) objectDB connected
2022-04-27 14:53:18.823 - debug: history.0 (4085778) Redis States: Use Redis connection: 0.0.0.0:9000
2022-04-27 14:53:18.832 - debug: history.0 (4085778) States create System PubSub Client
2022-04-27 14:53:18.833 - debug: history.0 (4085778) States create User PubSub Client
2022-04-27 14:53:18.844 - debug: history.0 (4085778) States connected to redis: 0.0.0.0:9000
2022-04-27 14:53:18.845 - debug: history.0 (4085778) statesDB connected
2022-04-27 14:53:19.057 - debug: history.0 (4085778) Plugin sentry Initialize Plugin (enabled=true)

Hab's nochmal versucht und finde:

2022-04-27 14:59:41.657 - debug: history.0 (4085778) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-27 14:59:50.596 - debug: history.0 (4085778) deleteState 1 items
2022-04-27 14:59:50.597 - warn: history.0 (4085778) States system pmessage io.messagebox.system.adapter.history.0 {"command":"delete","message":[{"state":{"ts":1651063995440},"id":"Messwerte.0.Stromzaehler.Summenwerte.Tagesendverbrauch"}],"from":"system.adapter.admin.0","callback":{"message":[{"state":{"ts":1651063995440},"id":"Messwerte.0.Stromzaehler.Summenwerte.Tagesendverbrauch"}],"id":325,"ack":false,"time":1651064390595},"_id":70540713} file is not defined
2022-04-27 14:59:50.604 - warn: history.0 (4085778) ReferenceError: file is not defined
at /opt/iobroker/node_modules/iobroker.history/main.js:1552:59
at Array.forEach ()
at _delete (/opt/iobroker/node_modules/iobroker.history/main.js:1505:15)
at deleteState (/opt/iobroker/node_modules/iobroker.history/main.js:1628:17)
at processMessage (/opt/iobroker/node_modules/iobroker.history/main.js:326:9)
at Object.message (/opt/iobroker/node_modules/iobroker.history/main.js:233:25)
at change (/opt/iobroker/node_modules/iobroker.js-controller/lib/adapter.js:5634:41)
at Immediate._onImmediate (/opt/iobroker/node_modules/@iobroker/db-states-redis/lib/states/statesInRedisClient.js:232:37)
at processImmediate (internal/timers.js:464:21)

[apollon77]

@homoran COngrats, you forund a bug :-)) Fixed ion GitHub

[Homoran]

@apollon77 Aber immer wieder gerne

EDIT:

Works like a charm!

Endlich kann ich meine Charts aufräumen.
Sind natürlich jetzt unendlich viele DPs mit Dp*8 null drin

[apollon77]

@homoran Viel Erfolg

[Homoran]

@apollon77 sagte in Test Adapter History 2.0.0:

@homoran COngrats, you forund a bug :-)) Fixed ion GitHub

ich versuche es gleich nochmal

Ich habe gerade festgestellt, dass in einem Chart eine Linie fehlte.
Beim Versuch die Daten des zuständigen Datenpunktes anzusehen scheint history abgestürzt zu sein:

2022-04-28 14:41:30.041 - error: host.iobroker-VM03 Caught by controller[0]: <--- Last few GCs --->
2022-04-28 14:41:30.043 - error: host.iobroker-VM03 Caught by controller[0]: [2034430:0x597ac00] 38402 ms: Mark-sweep 1862.1 (1987.9) -> 1858.1 (1990.6) MB, 1777.6 / 0.0 ms (average mu = 0.225, current mu = 0.029) allocation failure scavenge might not succeed
2022-04-28 14:41:30.043 - error: host.iobroker-VM03 Caught by controller[0]: [2034430:0x597ac00] 40186 ms: Mark-sweep 1874.1 (1990.6) -> 1870.1 (1993.1) MB, 1712.1 / 0.0 ms (average mu = 0.147, current mu = 0.040) allocation failure scavenge might not succeed
2022-04-28 14:41:30.043 - error: host.iobroker-VM03 Caught by controller[0]: <--- JS stacktrace --->
2022-04-28 14:41:30.043 - error: host.iobroker-VM03 Caught by controller[0]: FATAL ERROR: Ineffective mark-compacts near heap limit Allocation failed - JavaScript heap out of memory
2022-04-28 14:41:30.043 - error: host.iobroker-VM03 Caught by controller[1]: 1: 0xa3aaf0 node::Abort() [io.history.0]
2022-04-28 14:41:30.043 - error: host.iobroker-VM03 Caught by controller[2]: 2: 0x970199 node::FatalError(char const*, char const*) [io.history.0]
2022-04-28 14:41:30.044 - error: host.iobroker-VM03 Caught by controller[3]: 3: 0xbba45e v8::Utils::ReportOOMFailure(v8::internal::Isolate*, char const*, bool) [io.history.0]
2022-04-28 14:41:30.044 - error: host.iobroker-VM03 Caught by controller[4]: 4: 0xbba7d7 v8::internal::V8::FatalProcessOutOfMemory(v8::internal::Isolate*, char const*, bool) [io.history.0]
2022-04-28 14:41:30.044 - error: host.iobroker-VM03 Caught by controller[5]: 5: 0xd769e5 [io.history.0]
2022-04-28 14:41:30.044 - error: host.iobroker-VM03 Caught by controller[6]: 6: 0xd7756f [io.history.0]
2022-04-28 14:41:30.044 - error: host.iobroker-VM03 Caught by controller[7]: 7: 0xd853ab v8::internal::Heap::CollectGarbage(v8::internal::AllocationSpace, v8::internal::GarbageCollectionReason, v8::GCCallbackFlags) [io.history.0]
2022-04-28 14:41:30.044 - error: host.iobroker-VM03 Caught by controller[8]: 8: 0xd88f6c v8::internal::Heap::AllocateRawWithRetryOrFailSlowPath(int, v8::internal::AllocationType, v8::internal::AllocationOrigin, v8::internal::AllocationAlignment) [io.history.0]
2022-04-28 14:41:30.044 - error: host.iobroker-VM03 Caught by controller[9]: 9: 0xd4e73d v8::internal::Factory::AllocateRaw(int, v8::internal::AllocationType, v8::internal::AllocationAlignment) [io.history.0]
2022-04-28 14:41:30.044 - error: host.iobroker-VM03 Caught by controller[10]: 10: 0xd4a999 v8::internal::FactoryBase::AllocateRawArray(int, v8::internal::AllocationType) [io.history.0]
2022-04-28 14:41:30.044 - error: host.iobroker-VM03 Caught by controller[11]: 11: 0xd4aa54 v8::internal::FactoryBase::NewFixedArrayWithFiller(v8::internal::Handle, int, v8::internal::Handle, v8::internal::AllocationType) [io.history.0]
2022-04-28 14:41:30.044 - error: host.iobroker-VM03 Caught by controller[12]: 12: 0xf80ffe v8::internal::Handle v8::internal::HashTable::New(v8::internal::Isolate*, int, v8::internal::AllocationType, v8::internal::MinimumCapacity) [io.history.0]
2022-04-28 14:41:30.044 - error: host.iobroker-VM03 Caught by controller[13]: 13: 0xf81cf9 v8::internal::Handle v8::internal::BaseNameDictionary::New(v8::internal::Isolate*, int, v8::internal::AllocationType, v8::internal::MinimumCapacity) [io.history.0]
2022-04-28 14:41:30.044 - error: host.iobroker-VM03 Caught by controller[14]: 14: 0xf35be1 v8::internal::JSObject::MigrateToMap(v8::internal::Isolate*, v8::internal::Handle, v8::internal::Handle, int) [io.history.0]
2022-04-28 14:41:30.044 - error: host.iobroker-VM03 Caught by controller[15]: 15: 0xf556cc v8::internal::LookupIterator::Delete() [io.history.0]
2022-04-28 14:41:30.044 - error: host.iobroker-VM03 Caught by controller[16]: 16: 0xf2c55b v8::internal::JSReceiver::DeleteProperty(v8::internal::LookupIterator*, v8::internal::LanguageMode) [io.history.0]
2022-04-28 14:41:30.044 - error: host.iobroker-VM03 Caught by controller[17]: 17: 0x10b4600 v8::internal::Runtime::DeleteObjectProperty(v8::internal::Isolate*, v8::internal::Handle, v8::internal::Handle, v8::internal::LanguageMode) [io.history.0]
2022-04-28 14:41:30.045 - error: host.iobroker-VM03 Caught by controller[18]: 18: 0x10b673a v8::internal::Runtime_DeleteProperty(int, unsigned long*, v8::internal::Isolate*) [io.history.0]
2022-04-28 14:41:30.045 - error: host.iobroker-VM03 Caught by controller[19]: 19: 0x1448e19 [io.history.0]
2022-04-28 14:41:30.045 - warn: host.iobroker-VM03 instance system.adapter.history.0 terminated due to SIGABRT

EDIT:
Habe bei dem Datenpunkt Logging deaktiviert und neu konfiguriert.
Seitdem loggt er anscheinend wieder, aber sobald ich mir die Werte ansehen will stürzt history ab

[apollon77]

@homoran Wie lange zurück schaust Du denn? Das ist ein "Out of memory" ... Ansonsten Debug wieder an und dann mal log von so einem crash senden

[Homoran]

@apollon77 sagte in Test Adapter History 2.0.0:

Das ist ein "Out of memory"

Hatte ich gesehen
habe 4GB RAM zugewiesen und etwa 0,5GB free und 1,5 - 2 GB available

@apollon77 sagte in Test Adapter History 2.0.0:

Wie lange zurück schaust Du denn?

erst drei Tage, dann nur noch 10 Minuten.
Allerdings loggt er (???) alle 6 Sekunden

debug-LOG

2022-04-28 16:03:08.838 - info: history.0 (2087532) Loglevel changed from "info" to "debug"
2022-04-28 16:03:11.278 - debug: history.0 (2087532) getHistory call: {"id":"system.host.iobroker-VM03.load","path":"/opt/iobroker/iobroker-data/history/","start":1651151993789,"end":1651154589581,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":57,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:11.278 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:11.359 - debug: history.0 (2087532) got 2 datapoints for system.host.iobroker-VM03.load
2022-04-28 16:03:11.388 - debug: history.0 (2087532) Send: 30 of: 178 in: 110ms
2022-04-28 16:03:11.388 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:12.276 - debug: history.0 (2087532) getHistory call: {"id":"system.host.iobroker-VM03.freemem","path":"/opt/iobroker/iobroker-data/history/","start":1651151993789,"end":1651154589581,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"false","aggregate":"minmax","limit":2000,"addId":false,"sessionId":57,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:12.277 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:12.285 - debug: history.0 (2087532) getHistory call: {"id":"system.host.iobroker-VM03.inputCount","path":"/opt/iobroker/iobroker-data/history/","start":1651151993789,"end":1651154589581,"step":100000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"sessionId":57,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:12.285 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:12.293 - debug: history.0 (2087532) getHistory call: {"id":"system.host.iobroker-VM03.outputCount","path":"/opt/iobroker/iobroker-data/history/","start":1651151993789,"end":1651154589581,"step":100000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"sessionId":57,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:12.293 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:12.347 - debug: history.0 (2087532) got 2 datapoints for system.host.iobroker-VM03.freemem
2022-04-28 16:03:12.369 - debug: history.0 (2087532) got 0 datapoints for system.host.iobroker-VM03.inputCount
2022-04-28 16:03:12.380 - debug: history.0 (2087532) got 0 datapoints for system.host.iobroker-VM03.outputCount
2022-04-28 16:03:12.397 - debug: history.0 (2087532) Send: 30 of: 180 in: 121ms
2022-04-28 16:03:12.397 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:12.404 - debug: history.0 (2087532) Send: 16 of: 43 in: 111ms
2022-04-28 16:03:12.404 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:12.405 - debug: history.0 (2087532) Send: 11 of: 29 in: 120ms
2022-04-28 16:03:12.405 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:19.313 - debug: history.0 (2087532) getHistory call: {"id":"Messwerte.0.Stromzaehler.Summenwerte.Tagesendverbrauch","path":"/opt/iobroker/iobroker-data/history/","start":1650549799567,"end":1651154599567,"step":null,"count":300,"from":false,"ack":false,"q":false,"ignoreNull":"false","aggregate":"onchange","limit":300,"addId":false,"sessionId":27,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:19.313 - debug: history.0 (2087532) got 0 datapoints for Messwerte.0.Stromzaehler.Summenwerte.Tagesendverbrauch
2022-04-28 16:03:19.328 - debug: history.0 (2087532) Send: 11 values in: 15ms
2022-04-28 16:03:19.328 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:19.355 - debug: history.0 (2087532) getHistory call: {"id":"Messwerte.0.Solaranlage.Momentanwerte.Solarestimate2","path":"/opt/iobroker/iobroker-data/history/","start":1651096800000,"end":1651183200000,"step":null,"count":300,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":300,"addId":false,"sessionId":52,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:19.355 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:19.423 - debug: history.0 (2087532) got 1 datapoints for Messwerte.0.Solaranlage.Momentanwerte.Solarestimate2
2022-04-28 16:03:19.446 - debug: history.0 (2087532) Send: 125 of: 577 in: 91ms
2022-04-28 16:03:19.447 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:20.279 - debug: history.0 (2087532) getHistory call: {"id":"Messwerte.0.Stromzaehler.Summenwerte.Tagesendeinspeisung","path":"/opt/iobroker/iobroker-data/history/","start":1650539799567,"end":1651144599567,"step":null,"count":300,"from":false,"ack":false,"q":false,"ignoreNull":"false","aggregate":"onchange","limit":300,"addId":false,"sessionId":27,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:20.279 - debug: history.0 (2087532) got 0 datapoints for Messwerte.0.Stromzaehler.Summenwerte.Tagesendeinspeisung
2022-04-28 16:03:20.290 - debug: history.0 (2087532) Send: 9 values in: 11ms
2022-04-28 16:03:20.290 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:20.291 - debug: history.0 (2087532) getHistory call: {"id":"Messwerte.0.Stromzaehler.Summenwerte.Tagesendbezug","path":"/opt/iobroker/iobroker-data/history/","start":1650529799567,"end":1651134599567,"step":null,"count":300,"from":false,"ack":false,"q":false,"ignoreNull":"false","aggregate":"onchange","limit":300,"addId":false,"sessionId":27,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:20.291 - debug: history.0 (2087532) got 0 datapoints for Messwerte.0.Stromzaehler.Summenwerte.Tagesendbezug
2022-04-28 16:03:20.302 - debug: history.0 (2087532) Send: 9 values in: 11ms
2022-04-28 16:03:20.302 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:20.303 - debug: history.0 (2087532) getHistory call: {"id":"Messwerte.0.Solaranlage.Momentanwerte.Leistung_AC_aktuell","path":"/opt/iobroker/iobroker-data/history/","start":1651096800000,"end":1651183200000,"step":null,"count":300,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":300,"addId":false,"sessionId":52,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:20.303 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:20.310 - debug: history.0 (2087532) getHistory call: {"id":"Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch","path":"/opt/iobroker/iobroker-data/history/","start":1651096800000,"end":1651183200000,"step":null,"count":300,"from":false,"ack":false,"q":false,"ignoreNull":"false","aggregate":"minmax","limit":300,"addId":false,"sessionId":52,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:20.310 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:20.319 - debug: history.0 (2087532) getHistory call: {"id":"Messwerte.0.Stromzaehler.Momentanwerte.akt_Verbrauch","path":"/opt/iobroker/iobroker-data/history/","start":1651096800000,"end":1651183200000,"step":null,"count":300,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":300,"addId":false,"sessionId":52,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:20.319 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:20.326 - debug: history.0 (2087532) getHistory call: {"id":"Messwerte.0.Stromzaehler.Momentanwerte.akt_Einspeisung","path":"/opt/iobroker/iobroker-data/history/","start":1651096800000,"end":1651183200000,"step":null,"count":300,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":300,"addId":false,"sessionId":52,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:20.327 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:20.399 - debug: history.0 (2087532) got 5 datapoints for Messwerte.0.Solaranlage.Momentanwerte.Leistung_AC_aktuell
2022-04-28 16:03:20.403 - debug: history.0 (2087532) got 5 datapoints for Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch
2022-04-28 16:03:20.422 - debug: history.0 (2087532) got 5 datapoints for Messwerte.0.Stromzaehler.Momentanwerte.akt_Verbrauch
2022-04-28 16:03:20.434 - debug: history.0 (2087532) got 5 datapoints for Messwerte.0.Stromzaehler.Momentanwerte.akt_Einspeisung
2022-04-28 16:03:20.475 - debug: history.0 (2087532) Send: 124 of: 4473 in: 172ms
2022-04-28 16:03:20.475 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:20.488 - debug: history.0 (2087532) Send: 140 of: 4709 in: 178ms
2022-04-28 16:03:20.488 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:20.505 - debug: history.0 (2087532) Send: 133 of: 4387 in: 179ms
2022-04-28 16:03:20.505 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:20.509 - debug: history.0 (2087532) Send: 204 of: 7340 in: 191ms
2022-04-28 16:03:20.510 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:21.391 - debug: history.0 (2087532) getHistory call: {"id":"hm-rpc.0.NEQ0384904.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651111401646,"end":1651154601646,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":53,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:21.391 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:21.457 - debug: history.0 (2087532) got 0 datapoints for hm-rpc.0.NEQ0384904.2.POWER
2022-04-28 16:03:21.478 - debug: history.0 (2087532) Send: 26 of: 27 in: 87ms
2022-04-28 16:03:21.478 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:22.294 - debug: history.0 (2087532) getHistory call: {"id":"hm-rpc.0.LTK0124191.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651111401646,"end":1651154601646,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":53,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:22.294 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:22.303 - debug: history.0 (2087532) getHistory call: {"id":"hm-rpc.0.KEQ0966548.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651111401646,"end":1651154601646,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":53,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:22.304 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:22.311 - debug: history.0 (2087532) getHistory call: {"id":"hm-rpc.0.KEQ0967747.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651111401646,"end":1651154601646,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":53,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:22.311 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:22.321 - debug: history.0 (2087532) getHistory call: {"id":"hm-rpc.0.NEQ0386317.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651111401646,"end":1651154601646,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":53,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:22.322 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:22.332 - debug: history.0 (2087532) getHistory call: {"id":"hm-rpc.0.LTK0124795.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651111401646,"end":1651154601646,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":53,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:22.333 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:22.344 - debug: history.0 (2087532) getHistory call: {"id":"hm-rpc.0.LTK0124195.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651111401646,"end":1651154601646,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":53,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:22.344 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:22.374 - debug: history.0 (2087532) getHistory call: {"id":"hm-rpc.0.LTK0124798.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651111401646,"end":1651154601646,"step":100000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"sessionId":53,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:22.375 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:22.395 - debug: history.0 (2087532) getHistory call: {"id":"hm-rpc.0.LTK0124143.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651111401646,"end":1651154601646,"step":100000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"sessionId":53,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:22.395 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:22.446 - debug: history.0 (2087532) getHistory call: {"id":"hm-rpc.0.REQ0777349.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651111401646,"end":1651154601646,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":53,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:22.447 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:22.466 - debug: history.0 (2087532) getHistory call: {"id":"hm-rpc.0.NEQ0116043.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651111401646,"end":1651154601646,"step":100000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"sessionId":53,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:22.467 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:22.483 - debug: history.0 (2087532) getHistory call: {"id":"hm-rpc.1.0001D3C99C3EFB.6.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651111401646,"end":1651154601646,"step":100000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"sessionId":53,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:22.483 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:22.513 - debug: history.0 (2087532) got 0 datapoints for hm-rpc.0.LTK0124191.2.POWER
2022-04-28 16:03:22.523 - debug: history.0 (2087532) got 0 datapoints for hm-rpc.0.KEQ0966548.2.POWER
2022-04-28 16:03:22.525 - debug: history.0 (2087532) got 0 datapoints for hm-rpc.0.KEQ0967747.2.POWER
2022-04-28 16:03:22.528 - debug: history.0 (2087532) got 0 datapoints for hm-rpc.0.NEQ0386317.2.POWER
2022-04-28 16:03:22.530 - debug: history.0 (2087532) got 0 datapoints for hm-rpc.0.LTK0124795.2.POWER
2022-04-28 16:03:22.552 - debug: history.0 (2087532) Send: 192 of: 193 in: 258ms
2022-04-28 16:03:22.553 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:22.554 - debug: history.0 (2087532) Send: 165 of: 166 in: 251ms
2022-04-28 16:03:22.554 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:22.555 - debug: history.0 (2087532) Send: 84 of: 86 in: 244ms
2022-04-28 16:03:22.555 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:22.557 - debug: history.0 (2087532) got 0 datapoints for hm-rpc.0.LTK0124798.2.POWER
2022-04-28 16:03:22.573 - debug: history.0 (2087532) Send: 129 of: 134 in: 252ms
2022-04-28 16:03:22.574 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:22.584 - debug: history.0 (2087532) Send: 279 of: 339 in: 252ms
2022-04-28 16:03:22.584 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:22.607 - debug: history.0 (2087532) got 2 datapoints for hm-rpc.0.LTK0124195.2.POWER
2022-04-28 16:03:22.609 - debug: history.0 (2087532) Send: 292 of: 549 in: 235ms
2022-04-28 16:03:22.609 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:22.619 - debug: history.0 (2087532) got 0 datapoints for hm-rpc.0.REQ0777349.2.POWER
2022-04-28 16:03:22.652 - debug: history.0 (2087532) got 0 datapoints for hm-rpc.0.LTK0124143.2.POWER
2022-04-28 16:03:22.654 - debug: history.0 (2087532) Send: 367 of: 1181 in: 309ms
2022-04-28 16:03:22.654 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:22.670 - debug: history.0 (2087532) Send: 26 of: 27 in: 224ms
2022-04-28 16:03:22.670 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:22.677 - debug: history.0 (2087532) got 0 datapoints for hm-rpc.0.NEQ0116043.2.POWER
2022-04-28 16:03:22.681 - debug: history.0 (2087532) got 2 datapoints for hm-rpc.1.0001D3C99C3EFB.6.POWER
2022-04-28 16:03:22.696 - debug: history.0 (2087532) Send: 278 of: 280 in: 301ms
2022-04-28 16:03:22.697 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:22.705 - debug: history.0 (2087532) Send: 26 of: 27 in: 239ms
2022-04-28 16:03:22.706 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:22.709 - debug: history.0 (2087532) Send: 221 of: 508 in: 226ms
2022-04-28 16:03:22.709 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-04-28 16:03:24.438 - debug: history.0 (2087532) getHistory call: {"id":"Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch","path":"/opt/iobroker/iobroker-data/history/","start":1651068000000,"end":1651154604692,"step":3600000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:24.438 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:24.450 - debug: history.0 (2087532) getHistory call: {"id":"Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch","path":"/opt/iobroker/iobroker-data/history/","start":1651068000000,"end":1651154604705,"step":3600000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:24.450 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:24.473 - debug: history.0 (2087532) getHistory call: {"id":"Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch","path":"/opt/iobroker/iobroker-data/history/","start":1651068000000,"end":1651154604727,"step":3600000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:24.473 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:24.488 - debug: history.0 (2087532) getHistory call: {"id":"Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch","path":"/opt/iobroker/iobroker-data/history/","start":1651068000000,"end":1651154604743,"step":3600000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:24.489 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:24.520 - debug: history.0 (2087532) got 6 datapoints for Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch
2022-04-28 16:03:24.529 - debug: history.0 (2087532) getHistory call: {"id":"Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch","path":"/opt/iobroker/iobroker-data/history/","start":1651068000000,"end":1651154604783,"step":3600000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:03:24.529 - debug: history.0 (2087532) use parallel requests for getHistory
2022-04-28 16:03:24.549 - debug: history.0 (2087532) got 6 datapoints for Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch
2022-04-28 16:03:24.582 - debug: history.0 (2087532) got 6 datapoints for Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch
2022-04-28 16:03:24.591 - debug: history.0 (2087532) got 6 datapoints for Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch
2022-04-28 16:03:24.674 - debug: history.0 (2087532) got 6 datapoints for Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch
2022-04-28 16:03:24.676 - debug: history.0 (2087532) Send: 28 of: 7528 in: 226ms
2022-04-28 16:03:24.676 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.admin.0 from system.adapter.history.0
2022-04-28 16:03:24.700 - debug: history.0 (2087532) Send: 28 of: 7528 in: 262ms
2022-04-28 16:03:24.701 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.admin.0 from system.adapter.history.0
2022-04-28 16:03:24.712 - debug: history.0 (2087532) Send: 28 of: 7528 in: 239ms
2022-04-28 16:03:24.713 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.admin.0 from system.adapter.history.0
2022-04-28 16:03:24.724 - debug: history.0 (2087532) Send: 28 of: 7528 in: 236ms
2022-04-28 16:03:24.724 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.admin.0 from system.adapter.history.0
2022-04-28 16:03:24.765 - debug: history.0 (2087532) Send: 28 of: 7528 in: 236ms
2022-04-28 16:03:24.766 - debug: history.0 (2087532) sendTo "getHistory" to system.adapter.admin.0 from system.adapter.history.0
2022-04-28 16:03:26.310 - debug: history.0 (2087532) sendTo "features" to system.adapter.admin.0 from system.adapter.history.0
2022-04-28 16:03:26.395 - debug: history.0 (2087532) getHistory call: {"id":"Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch","path":"/opt/iobroker/iobroker-data/history/","start":946681200000,"end":1651154606644,"step":null,"count":500,"from":false,"ack":false,"q":false,"aggregate":"none","limit":1,"addId":false,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false}
2022-04-28 16:04:00.486 - warn: javascript.1 (229936) script.js.Hardware.netScan_ganzneu: no Data
2022-04-28 16:04:08.785 - error: host.iobroker-VM03 Caught by controller[0]: <--- Last few GCs --->
2022-04-28 16:04:08.788 - error: host.iobroker-VM03 Caught by controller[0]: [2087532:0x4b72c00] 3086022 ms: Mark-sweep 1870.7 (1991.6) -> 1866.7 (1994.1) MB, 1783.6 / 0.0 ms (average mu = 0.209, current mu = 0.037) allocation failure scavenge might not succeed
2022-04-28 16:04:08.788 - error: host.iobroker-VM03 Caught by controller[0]: [2087532:0x4b72c00] 3087879 ms: Mark-sweep 1882.7 (1994.1) -> 1878.8 (1996.6) MB, 1793.0 / 0.0 ms (average mu = 0.134, current mu = 0.035) allocation failure scavenge might not succeed
2022-04-28 16:04:08.788 - error: host.iobroker-VM03 Caught by controller[0]: <--- JS stacktrace --->
2022-04-28 16:04:08.788 - error: host.iobroker-VM03 Caught by controller[1]: FATAL ERROR: Ineffective mark-compacts near heap limit Allocation failed - JavaScript heap out of memory
2022-04-28 16:04:08.788 - error: host.iobroker-VM03 Caught by controller[2]: 1: 0xa3aaf0 node::Abort() [io.history.0]
2022-04-28 16:04:08.788 - error: host.iobroker-VM03 Caught by controller[3]: 2: 0x970199 node::FatalError(char const*, char const*) [io.history.0]
2022-04-28 16:04:08.788 - error: host.iobroker-VM03 Caught by controller[4]: 3: 0xbba45e v8::Utils::ReportOOMFailure(v8::internal::Isolate*, char const*, bool) [io.history.0]
2022-04-28 16:04:08.788 - error: host.iobroker-VM03 Caught by controller[5]: 4: 0xbba7d7 v8::internal::V8::FatalProcessOutOfMemory(v8::internal::Isolate*, char const*, bool) [io.history.0]
2022-04-28 16:04:08.788 - error: host.iobroker-VM03 Caught by controller[5]: 5: 0xd769e5 [io.history.0]
2022-04-28 16:04:08.788 - error: host.iobroker-VM03 Caught by controller[6]: 6: 0xd7756f [io.history.0]
2022-04-28 16:04:08.788 - error: host.iobroker-VM03 Caught by controller[7]: 7: 0xd853ab v8::internal::Heap::CollectGarbage(v8::internal::AllocationSpace, v8::internal::GarbageCollectionReason, v8::GCCallbackFlags) [io.history.0]
2022-04-28 16:04:08.789 - error: host.iobroker-VM03 Caught by controller[8]: 8: 0xd88f6c v8::internal::Heap::AllocateRawWithRetryOrFailSlowPath(int, v8::internal::AllocationType, v8::internal::AllocationOrigin, v8::internal::AllocationAlignment) [io.history.0]
2022-04-28 16:04:08.789 - error: host.iobroker-VM03 Caught by controller[9]: 9: 0xd4e73d v8::internal::Factory::AllocateRaw(int, v8::internal::AllocationType, v8::internal::AllocationAlignment) [io.history.0]
2022-04-28 16:04:08.789 - error: host.iobroker-VM03 Caught by controller[10]: 10: 0xd4a999 v8::internal::FactoryBase::AllocateRawArray(int, v8::internal::AllocationType) [io.history.0]
2022-04-28 16:04:08.789 - error: host.iobroker-VM03 Caught by controller[11]: 11: 0xd4aa54 v8::internal::FactoryBase::NewFixedArrayWithFiller(v8::internal::Handle, int, v8::internal::Handle, v8::internal::AllocationType) [io.history.0]
2022-04-28 16:04:08.789 - error: host.iobroker-VM03 Caught by controller[12]: 12: 0xf80ffe v8::internal::Handle v8::internal::HashTable::New(v8::internal::Isolate*, int, v8::internal::AllocationType, v8::internal::MinimumCapacity) [io.history.0]
2022-04-28 16:04:08.789 - error: host.iobroker-VM03 Caught by controller[13]: 13: 0xf81cf9 v8::internal::Handle v8::internal::BaseNameDictionary::New(v8::internal::Isolate*, int, v8::internal::AllocationType, v8::internal::MinimumCapacity) [io.history.0]
2022-04-28 16:04:08.789 - error: host.iobroker-VM03 Caught by controller[14]: 14: 0xf35be1 v8::internal::JSObject::MigrateToMap(v8::internal::Isolate*, v8::internal::Handle, v8::internal::Handle, int) [io.history.0]
2022-04-28 16:04:08.789 - error: host.iobroker-VM03 Caught by controller[15]: 15: 0xf556cc v8::internal::LookupIterator::Delete() [io.history.0]
2022-04-28 16:04:08.789 - error: host.iobroker-VM03 Caught by controller[16]: 16: 0xf2c55b v8::internal::JSReceiver::DeleteProperty(v8::internal::LookupIterator*, v8::internal::LanguageMode) [io.history.0]
2022-04-28 16:04:08.789 - error: host.iobroker-VM03 Caught by controller[17]: 17: 0x10b4600 v8::internal::Runtime::DeleteObjectProperty(v8::internal::Isolate*, v8::internal::Handle, v8::internal::Handle, v8::internal::LanguageMode) [io.history.0]
2022-04-28 16:04:08.789 - error: host.iobroker-VM03 Caught by controller[18]: 18: 0x10b673a v8::internal::Runtime_DeleteProperty(int, unsigned long*, v8::internal::Isolate*) [io.history.0]
2022-04-28 16:04:08.789 - error: host.iobroker-VM03 Caught by controller[18]: 19: 0x1448e19 [io.history.0]
2022-04-28 16:04:08.789 - warn: host.iobroker-VM03 instance system.adapter.history.0 terminated due to SIGABRT
2022-04-28 16:04:08.790 - info: host.iobroker-VM03 instance system.adapter.history.0 terminated with code NaN ()

scheint in Zeile 119 loszugehen

[apollon77]

@homoran siehst du wieviele getHistpry abfragen da quasi parallel kommen ?! Wow. Ixh schaue mal.

[apollon77]

@homoran Ich hab mal versucht was zu optimieren, aber das ist schwierig. versuch mal GitHub version. Sonst setzen wir einfach den Heap für deinen Prozess hoch

[apollon77]

Auf GitHub sind einige Fixes hinzugekommen und auch noch zwei neue Features:

• Bei storeState kann nun mittels dem Parameter "rule=true" festlegen das die Daten nicht direkt geloggt werden sondern alle Rules angewendet werden wie für jeden normalen Wert - inkl. Debounce u.ä. Bitte vorsichtig nutzen das nicht unabsichtlich Werte nicht geschrieben werden!
• Eine Einstellung pro Datenpunkt ist dazugekommen, mit der man angeben kann auf wie viele Stellen nach dem Komma die Werte beim lesen (GetHistory) gerundet werden.

[Homoran]

@apollon77 ich bekomme nicht mal einen sinnvollen debug-log zustande

2022-05-01 14:31:34.235 - info: host.iobroker-VM03 stopInstance system.adapter.history.0 (force=false, process=true)
2022-05-01 14:31:38.374 - error: host.iobroker-VM03 Caught by controller[0]: <--- Last few GCs --->
2022-05-01 14:31:38.374 - error: host.iobroker-VM03 Caught by controller[0]: [4074178:0x543dc00] 60961 ms: Mark-sweep 1870.8 (1993.1) -> 1866.8 (1995.6) MB, 1798.0 / 0.0 ms (average mu = 0.216, current mu = 0.040) allocation failure scavenge might not succeed
2022-05-01 14:31:38.374 - error: host.iobroker-VM03 Caught by controller[0]: [4074178:0x543dc00] 62792 ms: Mark-sweep 1882.7 (1995.6) -> 1878.8 (1998.4) MB, 1761.4 / 0.0 ms (average mu = 0.139, current mu = 0.038) allocation failure scavenge might not succeed
2022-05-01 14:31:38.375 - error: host.iobroker-VM03 Caught by controller[0]: <--- JS stacktrace --->
2022-05-01 14:31:38.375 - error: host.iobroker-VM03 Caught by controller[0]: FATAL ERROR: Ineffective mark-compacts near heap limit Allocation failed - JavaScript heap out of memory
2022-05-01 14:31:38.375 - error: host.iobroker-VM03 Caught by controller[1]: 1: 0xa3aaf0 node::Abort() [io.history.0]
2022-05-01 14:31:38.375 - error: host.iobroker-VM03 Caught by controller[2]: 2: 0x970199 node::FatalError(char const*, char const*) [io.history.0]
2022-05-01 14:31:38.375 - error: host.iobroker-VM03 Caught by controller[3]: 3: 0xbba45e v8::Utils::ReportOOMFailure(v8::internal::Isolate*, char const*, bool) [io.history.0]
2022-05-01 14:31:38.375 - error: host.iobroker-VM03 Caught by controller[4]: 4: 0xbba7d7 v8::internal::V8::FatalProcessOutOfMemory(v8::internal::Isolate*, char const*, bool) [io.history.0]
2022-05-01 14:31:38.375 - error: host.iobroker-VM03 Caught by controller[5]: 5: 0xd769e5 [io.history.0]
2022-05-01 14:31:38.375 - error: host.iobroker-VM03 Caught by controller[6]: 6: 0xd7756f [io.history.0]
2022-05-01 14:31:38.375 - error: host.iobroker-VM03 Caught by controller[7]: 7: 0xd853ab v8::internal::Heap::CollectGarbage(v8::internal::AllocationSpace, v8::internal::GarbageCollectionReason, v8::GCCallbackFlags) [io.history.0]
2022-05-01 14:31:38.375 - error: host.iobroker-VM03 Caught by controller[8]: 8: 0xd88f6c v8::internal::Heap::AllocateRawWithRetryOrFailSlowPath(int, v8::internal::AllocationType, v8::internal::AllocationOrigin, v8::internal::AllocationAlignment) [io.history.0]
2022-05-01 14:31:38.376 - error: host.iobroker-VM03 Caught by controller[9]: 9: 0xd4e73d v8::internal::Factory::AllocateRaw(int, v8::internal::AllocationType, v8::internal::AllocationAlignment) [io.history.0]
2022-05-01 14:31:38.376 - error: host.iobroker-VM03 Caught by controller[10]: 10: 0xd4a999 v8::internal::FactoryBase::AllocateRawArray(int, v8::internal::AllocationType) [io.history.0]
2022-05-01 14:31:38.376 - error: host.iobroker-VM03 Caught by controller[11]: 11: 0xd4aa54 v8::internal::FactoryBase::NewFixedArrayWithFiller(v8::internal::Handle, int, v8::internal::Handle, v8::internal::AllocationType) [io.history.0]
2022-05-01 14:31:38.376 - error: host.iobroker-VM03 Caught by controller[12]: 12: 0xf80ffe v8::internal::Handle v8::internal::HashTable::New(v8::internal::Isolate*, int, v8::internal::AllocationType, v8::internal::MinimumCapacity) [io.history.0]
2022-05-01 14:31:38.376 - error: host.iobroker-VM03 Caught by controller[13]: 13: 0xf81cf9 v8::internal::Handle v8::internal::BaseNameDictionary::New(v8::internal::Isolate*, int, v8::internal::AllocationType, v8::internal::MinimumCapacity) [io.history.0]
2022-05-01 14:31:38.376 - error: host.iobroker-VM03 Caught by controller[14]: 14: 0xf35be1 v8::internal::JSObject::MigrateToMap(v8::internal::Isolate*, v8::internal::Handle, v8::internal::Handle, int) [io.history.0]
2022-05-01 14:31:38.376 - error: host.iobroker-VM03 Caught by controller[15]: 15: 0xf556cc v8::internal::LookupIterator::Delete() [io.history.0]
2022-05-01 14:31:38.376 - error: host.iobroker-VM03 Caught by controller[16]: 16: 0xf2c55b v8::internal::JSReceiver::DeleteProperty(v8::internal::LookupIterator*, v8::internal::LanguageMode) [io.history.0]
2022-05-01 14:31:38.376 - error: host.iobroker-VM03 Caught by controller[17]: 17: 0x10b4600 v8::internal::Runtime::DeleteObjectProperty(v8::internal::Isolate*, v8::internal::Handle, v8::internal::Handle, v8::internal::LanguageMode) [io.history.0]
2022-05-01 14:31:38.376 - error: host.iobroker-VM03 Caught by controller[18]: 18: 0x10b673a v8::internal::Runtime_DeleteProperty(int, unsigned long*, v8::internal::Isolate*) [io.history.0]
2022-05-01 14:31:38.376 - error: host.iobroker-VM03 Caught by controller[19]: 19: 0x1448e19 [io.history.0]
2022-05-01 14:31:38.376 - warn: host.iobroker-VM03 instance system.adapter.history.0 terminated due to SIGABRT
2022-05-01 14:31:38.376 - info: host.iobroker-VM03 instance system.adapter.history.0 terminated with code NaN ()

Habe dann immer relativ schnell den debug wieder abschalten wollen um das log nicht zu groß werden zu lassen, aber anscheinend kommt nach dem Absturz erst wieder dei Meldungen vom Neustart und dann..

Spoiler

2022-05-01 14:31:41.918 - debug: history.0 (4075723) getHistory call: {"id":"Messwerte.0.Stromzaehler.Summenwerte.Tagesendverbrauch","path":"/opt/iobroker/iobroker-data/history/","start":1650803341003,"end":1651408141003,"step":null,"count":300,"from":false,"ack":false,"q":false,"ignoreNull":"false","aggregate":"onchange","limit":300,"addId":false,"sessionId":305,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:41.918 - debug: history.0 (4075723) got 1 datapoints for Messwerte.0.Stromzaehler.Summenwerte.Tagesendverbrauch
2022-05-01 14:31:41.964 - debug: history.0 (4075723) Send: 11 values in: 47ms
2022-05-01 14:31:41.964 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:41.967 - debug: history.0 (4075723) getHistory call: {"id":"Messwerte.0.Stromzaehler.Summenwerte.Tagesendeinspeisung","path":"/opt/iobroker/iobroker-data/history/","start":1650793341003,"end":1651398141003,"step":null,"count":300,"from":false,"ack":false,"q":false,"ignoreNull":"false","aggregate":"onchange","limit":300,"addId":false,"sessionId":305,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:41.967 - debug: history.0 (4075723) got 1 datapoints for Messwerte.0.Stromzaehler.Summenwerte.Tagesendeinspeisung
2022-05-01 14:31:41.988 - debug: history.0 (4075723) Send: 9 values in: 22ms
2022-05-01 14:31:41.988 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:41.989 - debug: history.0 (4075723) getHistory call: {"id":"Messwerte.0.Stromzaehler.Summenwerte.Tagesendbezug","path":"/opt/iobroker/iobroker-data/history/","start":1650783341003,"end":1651388141003,"step":null,"count":300,"from":false,"ack":false,"q":false,"ignoreNull":"false","aggregate":"onchange","limit":300,"addId":false,"sessionId":305,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:41.989 - debug: history.0 (4075723) got 1 datapoints for Messwerte.0.Stromzaehler.Summenwerte.Tagesendbezug
2022-05-01 14:31:42.010 - debug: history.0 (4075723) Send: 9 values in: 21ms
2022-05-01 14:31:42.010 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:42.861 - debug: history.0 (4075723) getHistory call: {"id":"Messwerte.0.Solaranlage.Momentanwerte.Solarestimate2","path":"/opt/iobroker/iobroker-data/history/","start":1651356000000,"end":1651442400000,"step":null,"count":300,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":300,"addId":false,"sessionId":608,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:42.861 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:42.894 - debug: history.0 (4075723) getHistory call: {"id":"Messwerte.0.Solaranlage.Momentanwerte.Leistung_AC_aktuell","path":"/opt/iobroker/iobroker-data/history/","start":1651356000000,"end":1651442400000,"step":null,"count":300,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":300,"addId":false,"sessionId":608,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:42.894 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:42.910 - debug: history.0 (4075723) getHistory call: {"id":"Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch","path":"/opt/iobroker/iobroker-data/history/","start":1651356000000,"end":1651442400000,"step":null,"count":300,"from":false,"ack":false,"q":false,"ignoreNull":"false","aggregate":"minmax","limit":300,"addId":false,"sessionId":608,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:42.910 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:42.921 - debug: history.0 (4075723) getHistory call: {"id":"Messwerte.0.Stromzaehler.Momentanwerte.akt_Verbrauch","path":"/opt/iobroker/iobroker-data/history/","start":1651356000000,"end":1651442400000,"step":null,"count":300,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":300,"addId":false,"sessionId":608,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:42.922 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:42.930 - debug: history.0 (4075723) getHistory call: {"id":"Messwerte.0.Stromzaehler.Momentanwerte.akt_Einspeisung","path":"/opt/iobroker/iobroker-data/history/","start":1651356000000,"end":1651442400000,"step":null,"count":300,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":300,"addId":false,"sessionId":608,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:42.930 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:42.987 - debug: history.0 (4075723) got 1 datapoints for Messwerte.0.Solaranlage.Momentanwerte.Solarestimate2
2022-05-01 14:31:43.008 - debug: history.0 (4075723) got 1 datapoints for Messwerte.0.Solaranlage.Momentanwerte.Leistung_AC_aktuell
2022-05-01 14:31:43.064 - debug: history.0 (4075723) got 3 datapoints for Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch
2022-05-01 14:31:43.070 - debug: history.0 (4075723) got 2 datapoints for Messwerte.0.Stromzaehler.Momentanwerte.akt_Einspeisung
2022-05-01 14:31:43.079 - debug: history.0 (4075723) Send: 109 of: 508 in: 219ms
2022-05-01 14:31:43.080 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:43.112 - debug: history.0 (4075723) got 2 datapoints for Messwerte.0.Stromzaehler.Momentanwerte.akt_Verbrauch
2022-05-01 14:31:43.140 - debug: history.0 (4075723) Send: 106 of: 3896 in: 245ms
2022-05-01 14:31:43.140 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:43.176 - debug: history.0 (4075723) Send: 111 of: 3484 in: 246ms
2022-05-01 14:31:43.176 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:43.188 - debug: history.0 (4075723) Send: 122 of: 4157 in: 278ms
2022-05-01 14:31:43.189 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:43.222 - debug: history.0 (4075723) Send: 185 of: 6716 in: 301ms
2022-05-01 14:31:43.222 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:43.590 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.NEQ0384904.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651364942685,"end":1651408142685,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":305,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:43.590 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:43.619 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.JEQ0646431.1.STATE","path":"/opt/iobroker/iobroker-data/history/","start":1651148942708,"end":1651408142708,"step":4800000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"onchange","limit":2000,"addId":false,"sessionId":401,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:43.619 - debug: history.0 (4075723) got 2 datapoints for hm-rpc.0.JEQ0646431.1.STATE
2022-05-01 14:31:43.646 - debug: history.0 (4075723) Send: 181 values in: 27ms
2022-05-01 14:31:43.646 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:43.663 - debug: history.0 (4075723) got 1 datapoints for hm-rpc.0.NEQ0384904.2.POWER
2022-05-01 14:31:43.668 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.LTK0124191.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651364942685,"end":1651408142685,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":305,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:43.669 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:43.676 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.KEQ0966548.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651364942685,"end":1651408142685,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":305,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:43.676 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:43.687 - debug: history.0 (4075723) Send: 27 of: 31 in: 96ms
2022-05-01 14:31:43.687 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:43.714 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.KEQ0967747.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651364942685,"end":1651408142685,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":305,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:43.714 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:43.723 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.NEQ0386317.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651364942685,"end":1651408142685,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":305,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:43.724 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:43.732 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.LTK0124795.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651364942685,"end":1651408142685,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":305,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:43.733 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:43.746 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.LTK0124195.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651364942685,"end":1651408142685,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":305,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:43.746 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:43.754 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.LTK0124798.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651364942685,"end":1651408142685,"step":100000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"sessionId":305,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:43.755 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:43.772 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.LTK0124143.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651364942685,"end":1651408142685,"step":100000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"sessionId":305,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:43.773 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:43.790 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.REQ0777349.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651364942685,"end":1651408142685,"step":100000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"true","aggregate":"minmax","limit":2000,"addId":false,"sessionId":305,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:43.790 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:43.810 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.NEQ0116043.2.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651364942685,"end":1651408142685,"step":100000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"sessionId":305,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:43.810 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:43.833 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.1.0001D3C99C3EFB.6.POWER","path":"/opt/iobroker/iobroker-data/history/","start":1651364942685,"end":1651408142685,"step":100000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"sessionId":305,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:43.834 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:43.847 - debug: history.0 (4075723) getHistory call: {"id":"Messwerte.0.Pool.Druck_real","path":"/opt/iobroker/iobroker-data/history/","start":1651148942708,"end":1651408142708,"step":4800000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"false","aggregate":"onchange","limit":2000,"addId":false,"sessionId":401,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:43.848 - debug: history.0 (4075723) got 1 datapoints for Messwerte.0.Pool.Druck_real
2022-05-01 14:31:43.933 - debug: history.0 (4075723) Send: 3073 values in: 86ms
2022-05-01 14:31:43.933 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:43.972 - debug: history.0 (4075723) got 1 datapoints for hm-rpc.0.LTK0124191.2.POWER
2022-05-01 14:31:43.975 - debug: history.0 (4075723) got 1 datapoints for hm-rpc.0.KEQ0966548.2.POWER
2022-05-01 14:31:43.994 - debug: history.0 (4075723) got 1 datapoints for hm-rpc.0.KEQ0967747.2.POWER
2022-05-01 14:31:44.012 - debug: history.0 (4075723) got 1 datapoints for hm-rpc.0.NEQ0386317.2.POWER
2022-05-01 14:31:44.020 - debug: history.0 (4075723) got 1 datapoints for hm-rpc.0.LTK0124795.2.POWER
2022-05-01 14:31:44.022 - debug: history.0 (4075723) got 1 datapoints for hm-rpc.0.LTK0124195.2.POWER
2022-05-01 14:31:44.048 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.JEQ0140901.1.TEMPERATURE","path":"/opt/iobroker/iobroker-data/history/","start":1651148942708,"end":1651408142708,"step":4800000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"sessionId":401,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:44.048 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:44.071 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.LEQ0773306.2.TEMPERATURE","path":"/opt/iobroker/iobroker-data/history/","start":1651148942708,"end":1651408142708,"step":4800000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"sessionId":401,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:44.072 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:44.093 - debug: history.0 (4075723) Send: 180 of: 185 in: 425ms
2022-05-01 14:31:44.094 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:44.096 - debug: history.0 (4075723) Send: 194 of: 198 in: 420ms
2022-05-01 14:31:44.096 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:44.100 - debug: history.0 (4075723) Send: 192 of: 197 in: 385ms
2022-05-01 14:31:44.100 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:44.101 - debug: history.0 (4075723) Send: 186 of: 199 in: 378ms
2022-05-01 14:31:44.102 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:44.103 - debug: history.0 (4075723) Send: 282 of: 313 in: 371ms
2022-05-01 14:31:44.104 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:44.109 - debug: history.0 (4075723) Send: 334 of: 592 in: 363ms
2022-05-01 14:31:44.109 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:44.113 - debug: history.0 (4075723) got 1 datapoints for hm-rpc.0.REQ0777349.2.POWER
2022-05-01 14:31:44.132 - debug: history.0 (4075723) got 1 datapoints for hm-rpc.0.LTK0124798.2.POWER
2022-05-01 14:31:44.134 - debug: history.0 (4075723) got 1 datapoints for hm-rpc.0.LTK0124143.2.POWER
2022-05-01 14:31:44.142 - debug: history.0 (4075723) got 1 datapoints for hm-rpc.0.NEQ0116043.2.POWER
2022-05-01 14:31:44.147 - debug: history.0 (4075723) got 1 datapoints for hm-rpc.1.0001D3C99C3EFB.6.POWER
2022-05-01 14:31:44.168 - debug: history.0 (4075723) Send: 27 of: 31 in: 378ms
2022-05-01 14:31:44.169 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:44.170 - debug: history.0 (4075723) Send: 302 of: 494 in: 416ms
2022-05-01 14:31:44.171 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:44.176 - debug: history.0 (4075723) Send: 283 of: 290 in: 404ms
2022-05-01 14:31:44.176 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:44.181 - debug: history.0 (4075723) Send: 28 of: 31 in: 371ms
2022-05-01 14:31:44.181 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:44.183 - debug: history.0 (4075723) Send: 35 of: 38 in: 350ms
2022-05-01 14:31:44.183 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:44.209 - debug: history.0 (4075723) got 1 datapoints for hm-rpc.0.JEQ0140901.1.TEMPERATURE
2022-05-01 14:31:44.212 - debug: history.0 (4075723) got 1 datapoints for hm-rpc.0.LEQ0773306.2.TEMPERATURE
2022-05-01 14:31:44.238 - debug: history.0 (4075723) Send: 60 of: 365 in: 167ms
2022-05-01 14:31:44.238 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:44.244 - debug: history.0 (4075723) Send: 62 of: 1409 in: 197ms
2022-05-01 14:31:44.244 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:48.540 - debug: history.0 (4075723) getHistory call: {"id":"Messwerte.0.Solaranlage.Momentanwerte.Leistung_AC_aktuell","path":"/opt/iobroker/iobroker-data/history/","start":1651320000000,"end":1651408312584,"step":3600000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:48.540 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:48.632 - debug: history.0 (4075723) getHistory call: {"id":"Messwerte.0.Solaranlage.Momentanwerte.Leistung_AC_aktuell","path":"/opt/iobroker/iobroker-data/history/","start":1651320000000,"end":1651408312667,"step":3600000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:48.632 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:48.644 - debug: history.0 (4075723) got 1 datapoints for Messwerte.0.Solaranlage.Momentanwerte.Leistung_AC_aktuell
2022-05-01 14:31:48.687 - debug: history.0 (4075723) getHistory call: {"id":"Messwerte.0.Solaranlage.Momentanwerte.Leistung_AC_aktuell","path":"/opt/iobroker/iobroker-data/history/","start":1651320000000,"end":1651408312693,"step":3600000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:48.688 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:48.761 - debug: history.0 (4075723) got 1 datapoints for Messwerte.0.Solaranlage.Momentanwerte.Leistung_AC_aktuell
2022-05-01 14:31:48.775 - debug: history.0 (4075723) Send: 20 of: 7471 in: 235ms
2022-05-01 14:31:48.775 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.admin.0 from system.adapter.history.0
2022-05-01 14:31:48.800 - debug: history.0 (4075723) got 1 datapoints for Messwerte.0.Solaranlage.Momentanwerte.Leistung_AC_aktuell
2022-05-01 14:31:48.874 - debug: history.0 (4075723) Send: 20 of: 7471 in: 243ms
2022-05-01 14:31:48.874 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.admin.0 from system.adapter.history.0
2022-05-01 14:31:48.927 - debug: history.0 (4075723) Send: 20 of: 7471 in: 240ms
2022-05-01 14:31:48.927 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.admin.0 from system.adapter.history.0
2022-05-01 14:31:53.487 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.JEQ0646431.1.STATE","path":"/opt/iobroker/iobroker-data/history/","start":1651149117531,"end":1651408317531,"step":4800000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"onchange","limit":2000,"addId":false,"sessionId":11,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:53.487 - debug: history.0 (4075723) got 2 datapoints for hm-rpc.0.JEQ0646431.1.STATE
2022-05-01 14:31:53.502 - debug: history.0 (4075723) Send: 186 values in: 15ms
2022-05-01 14:31:53.502 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:54.487 - debug: history.0 (4075723) getHistory call: {"id":"Messwerte.0.Pool.Druck_real","path":"/opt/iobroker/iobroker-data/history/","start":1651149117531,"end":1651408317531,"step":4800000,"count":500,"from":false,"ack":false,"q":false,"ignoreNull":"false","aggregate":"onchange","limit":2000,"addId":false,"sessionId":11,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:54.487 - debug: history.0 (4075723) got 1 datapoints for Messwerte.0.Pool.Druck_real
2022-05-01 14:31:54.511 - debug: history.0 (4075723) Send: 3076 values in: 24ms
2022-05-01 14:31:54.511 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:54.517 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.JEQ0140901.1.TEMPERATURE","path":"/opt/iobroker/iobroker-data/history/","start":1651149117531,"end":1651408317531,"step":4800000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"sessionId":11,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:54.517 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:54.526 - debug: history.0 (4075723) getHistory call: {"id":"hm-rpc.0.LEQ0773306.2.TEMPERATURE","path":"/opt/iobroker/iobroker-data/history/","start":1651149117531,"end":1651408317531,"step":4800000,"count":500,"from":false,"ack":false,"q":false,"aggregate":"minmax","limit":2000,"addId":false,"sessionId":11,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}
2022-05-01 14:31:54.527 - debug: history.0 (4075723) use parallel requests for getHistory
2022-05-01 14:31:54.612 - debug: history.0 (4075723) got 1 datapoints for hm-rpc.0.JEQ0140901.1.TEMPERATURE
2022-05-01 14:31:54.625 - debug: history.0 (4075723) got 1 datapoints for hm-rpc.0.LEQ0773306.2.TEMPERATURE
2022-05-01 14:31:54.649 - debug: history.0 (4075723) Send: 63 of: 1409 in: 132ms
2022-05-01 14:31:54.650 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:54.651 - debug: history.0 (4075723) Send: 60 of: 366 in: 125ms
2022-05-01 14:31:54.651 - debug: history.0 (4075723) sendTo "getHistory" to system.adapter.web.0 from system.adapter.history.0
2022-05-01 14:31:56.753 - debug: history.0 (4075723) sendTo "features" to system.adapter.admin.0 from system.adapter.history.0
2022-05-01 14:31:56.871 - debug: history.0 (4075723) getHistory call: {"id":"Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch","path":"/opt/iobroker/iobroker-data/history/","start":946681200000,"end":1651408320907,"step":null,"count":500,"from":false,"ack":false,"q":false,"aggregate":"none","limit":1,"addId":false,"returnNewestEntries":false,"percentile":null,"quantile":null,"integralUnit":null,"integralInterpolation":null,"removeBorderValues":false,"round":null}

wobei ich da auf die Schnelle nichts finde was zu dem DP gehört, den ich öffnen will:
Messwerte.0.Solaranlage.Momentanwerte.Eigenverbrauch

übrigens bei allen DPs, die im 6Sekunden-Takt loggen gleiches Problem.

@apollon77 sagte in Test Adapter History 2.0.0:

Auf GitHub sind einige Fixes hinzugekommen und auch noch zwei neue Features:

aber immer noch 2.0.0??

[apollon77]

@homoran wo kommen denn diese massiv vielen gehHistory calls her? Das sind ja mehrere pro Sekunde???
Vor allem
Für den gleichen Datenpunkt. Weil am Ende ist’s sehr einfach: jeder der calls lädt mindestens die Daten des gesamten Tages und dann werden die auf die relevante Zeit gekürzt quasi. Ich schaue nochmal ob man das optimieren kann

Und ja. GitHub weiter auf 2.0.0

[Homoran]

@apollon77 sagte in Test Adapter History 2.0.0:

wo kommen denn diese massiv vielen gehHistory calls her? Das sind ja mehrere pro Sekunde???
Vor allem
Für den gleichen Datenpunkt.

Ich denke, das liegt daran, dass ich gerade die History des Datenpunktes aufrufe
(Hoffentlich)

[apollon77]

@homoran dann wäre es aber schon ein Admin Bug das er soooooo oft fragt. Muss ich nochmal genau schauen.

[Homoran]

@apollon77 sagte in Test Adapter History 2.0.0:

ein Admin

ist aber noch 5.2.3

[apollon77]

@homoran Update mal?

[Homoran]

@apollon77 sagte in Test Adapter History 2.0.0:

@homoran Update mal?