[beantwortet] ioBroker.benchmark, und nun?
-
@hydrotec was genau hat dich erstaunen lassen? Das der redis Test am Ende sooooooooo viel länger braucht? Wiederhole redis nochmal mit dem js-Controller 4 und du wirst noch mehr staunen
Das liegt daran das im Controller 3.x die „Objekt löschen“ Performance im redis unterirdisch ist. Und der Benchmark muss immer wieder viele Objekte löschen … aber wenn du bei deinen Testergebnisse mal in actionsPerSecMean schaust siehst du das redis bei dir in vielen Fällen fast doppelt so viel Durchsatz hat (bei setState sind es grob 2k/s zu 4k/s). Also aktuell nicht so auf die Gesamtlaufzeit schauen weil mit Controller 3.3 ist die stark verfälscht wegen cleanup Zeiten. Bzw klar ist der objectDelete Test bei redis ebenso schlecht.
Meintest du das?
-
@apollon77 sagte in [beantwortet] ioBroker.benchmark, und nun?:
Meintest du das?
Ja, Danke zu deiner Erläuterung
Am meisten hat mich die Verdoppelung des Durchsatzes erstaunt.
Aktuell läuft der Benchmark unter js-Controller 4 mit DB jsonl.
Wenn der durch ist, stelle ich nochmal auf Redis um, und wiederhole den Benchmark.
Ergebnisse folgen -
@hydrotec Cool, Danke! Am Ende passen Deine Werte reicht gut zu dmem anderen Ryzen 3400G den wir schonmal im benchmark hatten (https://www.iobroker.net/#de/blog/2021_12_15) ... Ich nehme Deine Zahlen mit auf. Und ja Controller 4 Zahlen sind auch super
-
@apollon77 sagte in [beantwortet] ioBroker.benchmark, und nun?:
Deine Werte reicht gut zu dmem anderen Ryzen 3400G den wir schonmal im benchmark hatten
Die Zahlen waren auch von mir
Was habt ihr mit dem Benchmark angestellt, der rennt ja wie nicht von dieser Welt.
Dachte erst ich hätte irgend etwas falsch gemachtWie angekündigt, folgend die Zahlen unter js-Controller 4.
Systeminfos sind die gleichen wie hier, bis auf
Host: admin: v5.2.3 js-controller: v4.0.4 -
wie besprochen, die Ergebnisse des Benchmark unter js-controller: 4.0.9
admin: v5.3.0 node: v14.19.0 js-controller: 4.0.9 benchmark Version 0.5.0 benchmark redis:redis Redis Sets are already activated. Laufzeit: 4,5 Std. -
Ich hoffe es ist ok wenn ich mal Deine Ergebnisse hier darstelle
Hier der vergleich von dem System von @hydrotec 4.0.x jsonl zu Redis in 3.3, 4.0 mit und ohne die "Sets" Optimierung.
Bei Object Creations/Deletions haben die Sets einen kleinen "Negativeffekt" auf den Durchsatz, aber nicht auf den States durchsatz. Positiv ist dann aber der Effekt bei den objectView*, wo es bei redis dann wegen den Sets schneller wird.
-
Der Vollständigkeit halber, hier noch der aktuelle Benchmark unter js-controller: 4.0.10
admin: v5.3.0 node: v14.19.0 js-controller: 4.0.10 benchmark Version 0.5.0 benchmark redis:redis Laufzeit: 4,25 Std. -
@hydrotec sagte in [beantwortet] ioBroker.benchmark, und nun?:
Keine großartigen Änderungen zu oben ... Es mittelt sich jetzt nur bissl anders wegen effektiv 20 Messpunkten
-
Moin moin,
ich habe gestern auch den Benchmark-Adapter gestartet aber nicht realisiert wie lang es dauern kann.
Benchmark läuft jetzt ca. 14 h und langsam mache ich mir sorgen, kann man den Fortschritt auf der Shell sehen?
Ich verwende einen Pi4 4GB mit SSD
node: v16.14.0
npm: 8.3.1
js-controller: v4.0.15 -
@thomas4170 im aktuellen logfile von ioBroker siehst du was gerade passiert und wie weit der Benchmark ist.
-
@feuersturm kannst Du mir bitte Aufzeigen wie ich da ran komme?
-
Ich habe mit
cd /opt/iobroker/logund
lsdas Log
iobroker.2022-02-26.logmit nano geöffnet und das steht im Log.
2022-02-26 15:45:45.457 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker add admin --host PI-IOBROKER 2022-02-26 15:45:47.620 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER create instance admin 2022-02-26 15:45:47.632 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object admin.1.info.updatesList created 2022-02-26 15:45:47.664 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object admin.1.info.newsLastId created 2022-02-26 15:45:47.696 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object admin.1.info.newsETag created 2022-02-26 15:45:47.728 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object admin.1.info.newsFeed created 2022-02-26 15:45:47.760 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object admin.1.info.connection created 2022-02-26 15:45:47.792 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object admin.1 created 2022-02-26 15:45:47.823 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object admin.1.info created 2022-02-26 15:45:47.860 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object system.adapter.admin.upload created 2022-02-26 15:45:47.896 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object system.adapter.admin.1.logLevel created 2022-02-26 15:45:47.930 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object system.adapter.admin.1.sigKill created 2022-02-26 15:45:47.964 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object system.adapter.admin.1.eventLoopLag created 2022-02-26 15:45:47.999 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object system.adapter.admin.1.outputCount created 2022-02-26 15:45:48.036 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object system.adapter.admin.1.inputCount created 2022-02-26 15:45:48.076 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object system.adapter.admin.1.uptime created 2022-02-26 15:45:48.115 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object system.adapter.admin.1.memRss created 2022-02-26 15:45:48.154 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object system.adapter.admin.1.memHeapTotal created 2022-02-26 15:45:48.189 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object system.adapter.admin.1.memHeapUsed created 2022-02-26 15:45:48.227 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object system.adapter.admin.1.cputime created 2022-02-26 15:45:48.264 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object system.adapter.admin.1.cpu created 2022-02-26 15:45:48.301 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object system.adapter.admin.1.compactMode created 2022-02-26 15:45:48.337 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object system.adapter.admin.1.connected created 2022-02-26 15:45:48.375 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object system.adapter.admin.1.alive created 2022-02-26 15:45:48.410 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER Set default value of admin.1.info.updatesList: 2022-02-26 15:45:48.438 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER Set default value of admin.1.info.connection: 2022-02-26 15:45:48.507 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER object system.adapter.admin.1 created 2022-02-26 15:45:49.546 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker exit 0 2022-02-26 15:45:51.565 - ^[[32minfo^[[39m: host.PI-IOBROKER instance system.adapter.admin.1 started with pid 20365 2022-02-26 15:45:53.967 - ^[[32minfo^[[39m: admin.1 (20365) starting. Version 5.2.3 in /opt/iobroker/node_modules/iobroker.admin, node: v16.14.0, js-controller: 3.3.22 2022-02-26 15:45:54.021 - ^[[32minfo^[[39m: admin.1 (20365) requesting all states 2022-02-26 15:45:54.023 - ^[[32minfo^[[39m: admin.1 (20365) requesting all objects 2022-02-26 15:45:54.843 - ^[[32minfo^[[39m: admin.1 (20365) received all objects 2022-02-26 15:45:55.152 - ^[[31merror^[[39m: admin.1 (20365) port 8081 already in use 2022-02-26 15:45:55.154 - ^[[32minfo^[[39m: admin.1 (20365) Terminated (ADAPTER_REQUESTED_TERMINATION): port 8081 already in use 2022-02-26 15:45:55.670 - ^[[32minfo^[[39m: admin.1 (20365) terminating http server on port 8081 2022-02-26 15:45:55.722 - ^[[31merror^[[39m: host.PI-IOBROKER instance system.adapter.admin.1 terminated by request of the instance itself and will not be restarted, before user restarts it. 2022-02-26 15:45:55.724 - ^[[32minfo^[[39m: host.PI-IOBROKER Do not restart adapter system.adapter.admin.1 because desired by instance 2022-02-26 15:46:21.842 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker del admin.1 2022-02-26 15:46:23.947 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker Delete adapter "admin.1" 2022-02-26 15:46:23.959 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER Counted 1 instances of admin.1 2022-02-26 15:46:23.971 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER Counted 1 channels of admin.1 2022-02-26 15:46:23.977 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER Counted 9 states of admin.1 2022-02-26 15:46:23.984 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER Counted 15 states of system.adapter.admin.1 2022-02-26 15:46:23.990 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER Counted 8 states (admin.1.*) from states 2022-02-26 15:46:23.992 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER Counted 15 states (system.adapter.admin.1.*) from states 2022-02-26 15:46:24.672 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER Counted 1 objects of admin.1 2022-02-26 15:46:24.674 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER Deleting 27 object(s). 2022-02-26 15:46:25.210 - ^[[32minfo^[[39m: host.PI-IOBROKER object deleted system.adapter.admin.1 2022-02-26 15:46:25.239 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER Deleting 23 state(s). 2022-02-26 15:46:26.307 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker exit 0 2022-02-26 15:52:13.099 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker upgrade admin@5.3.1 2022-02-26 15:52:15.458 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker Update admin from @5.2.3 to @5.3.1 2022-02-26 15:52:15.486 - ^[[32minfo^[[39m: host.PI-IOBROKER iobroker host.PI-IOBROKER Adapter "system.adapter.admin.0" is stopped. 2022-02-26 15:52:15.520 - ^[[32minfo^[[39m: admin.0 (9706) Adapter is disabled => stop 2022-02-26 15:52:15.532 - ^[[32minfo^[[39m: host.PI-IOBROKER "system.adapter.admin.0" disabled 2022-02-26 15:52:15.526 - ^[[32minfo^[[39m: admin.0 (9706) terminating http server on port 8081 2022-02-26 15:52:15.534 - ^[[32minfo^[[39m: host.PI-IOBROKER stopInstance system.adapter.admin.0 (force=false, process=true)Irgendwie sieht das so aus als passiert da nichts, oder?
-
@thomas4170 sagte in [beantwortet] ioBroker.benchmark, und nun?:
mit nano geöffnet und das steht im Log.
das ist ja auch nur eine Momentaufnahme!
"live" kannst du es mit
iobroker logs --watchsehen -
@homoran Danke für die Info
-
@thomas4170 In dem Log sehe ich nicht mal das benchmark überhaupt gestartet wurde, man sieht das jemand eine zweie Admin instanz angelegt hat und die admin0 gestoppt hat
-
@apollon77 Ja das ist versehentlich bei Update des Adapter passiert, hatte ich aber wieder deinstalliert. Mehr gibt dasLog nicht her
-
@thomas4170 also dann würde ich sagen starte mal Admin.0 wieder und schau nochmal. Ich sehe da nix von Benchmark at all
-
@apollon77 Ok versuche ich mal
-
Ich hole diesen Topic mal nach oben.
Bei mir scheint der Bechmark (gestartet gestern gegen 10:00) zu "hängen" - das sind die letzten Loggings - Log für heute ist leer ...
martin@iobroker-test-sicher:~$ tail /opt/iobroker/log/iobroker.2023-06-21.log 2023-06-21 13:57:36.137 - error: benchmark.0 (8223) Exception-Code: 22: Command failed: "/usr/bin/node" "/opt/iobroker/node_modules/iobroker.js-controller/iobroker.js" del benchmark.1 2023-06-21 13:57:36.141 - info: benchmark.0 (8223) terminating 2023-06-21 13:57:36.143 - warn: benchmark.0 (8223) Terminated (UNCAUGHT_EXCEPTION): Without reason 2023-06-21 13:57:38.586 - error: host.iobroker-test-sicher instance system.adapter.benchmark.0 terminated with code 6 (UNCAUGHT_EXCEPTION) 2023-06-21 13:57:38.588 - info: host.iobroker-test-sicher Restart adapter system.adapter.benchmark.0 because enabled 2023-06-21 13:58:10.589 - info: host.iobroker-test-sicher instance system.adapter.benchmark.0 started with pid 9628 2023-06-21 13:58:28.269 - warn: benchmark.0 (9628) slow connection to states DB. Still waiting ... 2023-06-21 13:58:35.054 - info: benchmark.0 (9628) starting. Version 1.1.4 in /opt/iobroker/node_modules/iobroker.benchmark, node: v16.20.0, js-controller: 4.0.24 2023-06-21 13:58:35.166 - info: benchmark.0 (9628) Adapter started... controller determined (pid: 354)martin@iobroker-test-sicher:~$ tail /opt/iobroker/log/iobroker.2023-06-22.log martin@iobroker-test-sicher:~$ ls /opt/iobroker/log/ iobroker-audit.json iobroker.2023-06-17.log.gz iobroker.2023-06-20.log.gz iobroker.current.log iobroker.2023-06-15.log.gz iobroker.2023-06-18.log.gz iobroker.2023-06-21.log iobroker.2023-06-16.log.gz iobroker.2023-06-19.log.gz iobroker.2023-06-22.log martin@iobroker-test-sicher:~$ tail /opt/iobroker/log/iobroker.current.log martin@iobroker-test-sicher:~$Kann man den Benchmark irgendwie "abschießen", und den ioBroker in den "normalbetrieb" zurückversetzen?
Letzter gestarteter Test scheint zu sein
2023-06-21 11:41:10.858 - info: benchmark.0 (8223) Starting test "setStatesParallel" -
@martinp sagte in [beantwortet] ioBroker.benchmark, und nun?:
2023-06-21 13:57:38.586 - error: host.iobroker-test-sicher instance system.adapter.benchmark.0 terminated with code 6 (UNCAUGHT_EXCEPTION)das hab ich im anderen Thread wohl übersehen
@martinp sagte in [beantwortet] ioBroker.benchmark, und nun?:
Kann man den Benchmark irgendwie "abschießen",
ich würde es mit
iob stop benchmarkversuchen und zur Sicherheit mittopmal nachsehen was da noch läuft.
dann ggf. mit Instanz ID wiederholen z.B.iob stop benchmark.24
