search

AWhiteKnight / ioBroker.moma

Adapter for monitoring and maintenance of an ioBroker multihost installation
MIT License
4 stars 3 forks source link

BUG Report #24

Closed sigi2345 closed 4 years ago

sigi2345 commented 5 years ago

Hallo, leider kommt immer dieser Fehler und Status LED springt von Rot auf Grün.

Versions:

Platform: Windows Architecture: x64 CPUs: 4 Speed: 3912 MHz Model: Intel(R) Core(TM) i3-7100 CPU @ 3.90GHz RAM: 7.9 GB System uptime: 1 T. 15:31:18 Node.js: v8.16.0 NPM: 6.4.1 adapters count: 269 Uptime: 05:04:56 Active instances: 41

undefined2019-05-11 14:15:18.908 - info: host.Medion object change system.adapter.moma.0 2019-05-11 14:15:18.976 - info: host.Medion instance system.adapter.moma.0 started with pid 14896 2019-05-11 14:15:19.416 - debug: moma.0 objectDB connected 2019-05-11 14:15:19.427 - debug: moma.0 statesDB connected 2019-05-11 14:15:19.438 - info: moma.0 starting. Version 1.0.0 in C:/ioBroker/node_modules/iobroker.moma, node: v8.16.0 2019-05-11 14:15:19.450 - debug: moma.0 starting adapter 2019-05-11 14:15:19.454 - debug: moma.0 creating moma meta entries 2019-05-11 14:15:19.455 - debug: moma.0 creating moma instance entries 2019-05-11 14:15:19.553 - debug: moma.0 running Once 2019-05-11 14:15:19.555 - debug: moma.0 running Interval0 2019-05-11 14:15:19.570 - debug: moma.0 running Interval1 2019-05-11 14:15:19.577 - debug: moma.0 running Interval2 2019-05-11 14:15:19.579 - debug: moma.0 running Interval3 2019-05-11 14:15:19.582 - debug: moma.0 running Interval4 2019-05-11 14:15:21.837 - error: moma.0 uncaught exception: Maximum call stack size exceeded 2019-05-11 14:15:21.837 - error: moma.0 RangeError: Maximum call stack size exceeded at C:\ioBroker\node_modules\ws\lib\Receiver.js:529:40 at Receiver.applyExtensions (C:\ioBroker\node_modules\ws\lib\Receiver.js:371:5) at C:\ioBroker\node_modules\ws\lib\Receiver.js:508:14 at Receiver.flush (C:\ioBroker\node_modules\ws\lib\Receiver.js:347:3) at C:\ioBroker\node_modules\ws\lib\Receiver.js:349:10 at C:\ioBroker\node_modules\ws\lib\Receiver.js:538:11 at Receiver.applyExtensions (C:\ioBroker\node_modules\ws\lib\Receiver.js:371:5) at C:\ioBroker\node_modules\ws\lib\Receiver.js:508:14 at Receiver.flush (C:\ioBroker\node_modules\ws\lib\Receiver.js:347:3) at C:\ioBroker\node_modules\ws\lib\Receiver.js:349:10 2019-05-11 14:15:21.839 - info: moma.0 cleaned everything up... 2019-05-11 14:15:21.906 - error: Caught by controller[0]: RangeError: Maximum call stack size exceeded 2019-05-11 14:15:21.906 - error: Caught by controller[0]: at C:\ioBroker\node_modules\ws\lib\Receiver.js:529:40 2019-05-11 14:15:21.906 - error: Caught by controller[0]: at Receiver.applyExtensions (C:\ioBroker\node_modules\ws\lib\Receiver.js:371:5) 2019-05-11 14:15:21.906 - error: Caught by controller[0]: at C:\ioBroker\node_modules\ws\lib\Receiver.js:508:14 2019-05-11 14:15:21.906 - error: Caught by controller[0]: at Receiver.flush (C:\ioBroker\node_modules\ws\lib\Receiver.js:347:3) 2019-05-11 14:15:21.906 - error: Caught by controller[0]: at C:\ioBroker\node_modules\ws\lib\Receiver.js:349:10 2019-05-11 14:15:21.906 - error: Caught by controller[0]: at C:\ioBroker\node_modules\ws\lib\Receiver.js:538:11 2019-05-11 14:15:21.906 - error: Caught by controller[0]: at Receiver.applyExtensions (C:\ioBroker\node_modules\ws\lib\Receiver.js:371:5) 2019-05-11 14:15:21.906 - error: Caught by controller[0]: at C:\ioBroker\node_modules\ws\lib\Receiver.js:508:14 2019-05-11 14:15:21.906 - error: Caught by controller[0]: at Receiver.flush (C:\ioBroker\node_modules\ws\lib\Receiver.js:347:3) 2019-05-11 14:15:21.906 - error: Caught by controller[0]: at C:\ioBroker\node_modules\ws\lib\Receiver.js:349:10 2019-05-11 14:15:21.906 - error: host.Medion instance system.adapter.moma.0 terminated with code 0 (OK) 2019-05-11 14:15:21.906 - info: host.Medion Restart adapter system.adapter.moma.0 because enabled 2019-05-11 14:15:51.956 - info: host.Medion instance system.adapter.moma.0 started with pid 14152 2019-05-11 14:15:52.311 - debug: moma.0 objectDB connected 2019-05-11 14:15:52.326 - debug: moma.0 statesDB connected 2019-05-11 14:15:52.341 - info: moma.0 starting. Version 1.0.0 in C:/ioBroker/node_modules/iobroker.moma, node: v8.16.0 2019-05-11 14:15:52.341 - debug: moma.0 starting adapter 2019-05-11 14:15:52.356 - debug: moma.0 creating moma meta entries 2019-05-11 14:15:52.356 - debug: moma.0 creating moma instance entries 2019-05-11 14:15:52.419 - debug: moma.0 running Once 2019-05-11 14:15:52.419 - debug: moma.0 running Interval0 2019-05-11 14:15:52.434 - debug: moma.0 running Interval1 2019-05-11 14:15:52.439 - debug: moma.0 running Interval2 2019-05-11 14:15:52.440 - debug: moma.0 running Interval3 2019-05-11 14:15:52.443 - debug: moma.0 running Interval4 2019-05-11 14:15:53.914 - info: host.Medion object change system.adapter.moma.0 2019-05-11 14:15:53.914 - info: host.Medion stopInstance system.adapter.moma.0 2019-05-11 14:15:53.914 - info: host.Medion stopInstance system.adapter.moma.0 killing pid 14152 2019-05-11 14:15:53.974 - warn: host.Medion instance system.adapter.moma.0 terminated due to SIGTERM 2019-05-11 14:15:53.974 - info: host.Medion instance system.adapter.moma.0 terminated with code null () 2019-05-11 14:15:55.322 - info: host.Medion object change system.adapter.moma.0 2019-05-11 14:15:55.363 - info: host.Medion instance system.adapter.moma.0 started with pid 18356 2019-05-11 14:15:55.698 - debug: moma.0 objectDB connected 2019-05-11 14:15:55.714 - debug: moma.0 statesDB connected 2019-05-11 14:15:55.726 - info: moma.0 starting. Version 1.0.0 in C:/ioBroker/node_modules/iobroker.moma, node: v8.16.0 2019-05-11 14:15:55.734 - debug: moma.0 starting adapter 2019-05-11 14:15:55.737 - debug: moma.0 creating moma meta entries 2019-05-11 14:15:55.738 - debug: moma.0 creating moma instance entries 2019-05-11 14:15:55.780 - debug: moma.0 running Once 2019-05-11 14:15:55.796 - debug: moma.0 running Interval0 2019-05-11 14:15:55.796 - debug: moma.0 running Interval1 2019-05-11 14:15:55.811 - debug: moma.0 running Interval2 2019-05-11 14:15:55.811 - debug: moma.0 running Interval3 2019-05-11 14:15:55.811 - debug: moma.0 running Interval4 2019-05-11 14:15:57.753 - error: moma.0 uncaught exception: Maximum call stack size exceeded 2019-05-11 14:15:57.753 - error: moma.0 RangeError: Maximum call stack size exceeded at C:\ioBroker\node_modules\ws\lib\Receiver.js:529:40 at Receiver.applyExtensions (C:\ioBroker\node_modules\ws\lib\Receiver.js:371:5) at C:\ioBroker\node_modules\ws\lib\Receiver.js:508:14 at Receiver.flush (C:\ioBroker\node_modules\ws\lib\Receiver.js:347:3) at C:\ioBroker\node_modules\ws\lib\Receiver.js:349:10 at C:\ioBroker\node_modules\ws\lib\Receiver.js:538:11 at Receiver.applyExtensions (C:\ioBroker\node_modules\ws\lib\Receiver.js:371:5) at C:\ioBroker\node_modules\ws\lib\Receiver.js:508:14 at Receiver.flush (C:\ioBroker\node_modules\ws\lib\Receiver.js:347:3) at C:\ioBroker\node_modules\ws\lib\Receiver.js:349:10 2019-05-11 14:15:57.754 - info: moma.0 cleaned everything up... 2019-05-11 14:15:57.821 - error: Caught by controller[0]: RangeError: Maximum call stack size exceeded 2019-05-11 14:15:57.821 - error: Caught by controller[0]: at C:\ioBroker\node_modules\ws\lib\Receiver.js:529:40 2019-05-11 14:15:57.821 - error: Caught by controller[0]: at Receiver.applyExtensions (C:\ioBroker\node_modules\ws\lib\Receiver.js:371:5) 2019-05-11 14:15:57.821 - error: Caught by controller[0]: at C:\ioBroker\node_modules\ws\lib\Receiver.js:508:14 2019-05-11 14:15:57.821 - error: Caught by controller[0]: at Receiver.flush (C:\ioBroker\node_modules\ws\lib\Receiver.js:347:3) 2019-05-11 14:15:57.822 - error: Caught by controller[0]: at C:\ioBroker\node_modules\ws\lib\Receiver.js:349:10 2019-05-11 14:15:57.822 - error: Caught by controller[0]: at C:\ioBroker\node_modules\ws\lib\Receiver.js:538:11 2019-05-11 14:15:57.822 - error: Caught by controller[0]: at Receiver.applyExtensions (C:\ioBroker\node_modules\ws\lib\Receiver.js:371:5) 2019-05-11 14:15:57.822 - error: Caught by controller[0]: at C:\ioBroker\node_modules\ws\lib\Receiver.js:508:14 2019-05-11 14:15:57.822 - error: Caught by controller[0]: at Receiver.flush (C:\ioBroker\node_modules\ws\lib\Receiver.js:347:3) 2019-05-11 14:15:57.822 - error: Caught by controller[0]: at C:\ioBroker\node_modules\ws\lib\Receiver.js:349:10 2019-05-11 14:15:57.822 - error: host.Medion instance system.adapter.moma.0 terminated with code 0 (OK) 2019-05-11 14:15:57.822 - info: host.Medion Restart adapter system.adapter.moma.0 because enabled

AWhiteKnight commented 5 years ago

Was soll der Adapter alles loggen? Unter Umständen wird die Maschine gerade überfordert, weil sich die Aufrufe überholen. Gegebenenfalls den Loggingumfang mal stark reduzieren. Kommt der Fehler dann noch?

sigi2345 commented 5 years ago

Ich lasse fast Alles loggen.......der Fehler kommt nur am Anfang nach einer gewissen Zeit geht es. Neuinstallation ist das selbe.....ist Wahrscheinlich mit den Anfragen überfordert.

`

moma.0 2019-05-11 23:16:34.483 error error deleting objects from uuid: ReferenceError: err is not defined
moma.0 2019-05-11 23:16:32.750 debug running Interval4
moma.0 2019-05-11 23:16:32.750 debug running Interval3
moma.0 2019-05-11 23:16:32.750 debug running Interval2
moma.0 2019-05-11 23:16:32.750 debug running Interval1
moma.0 2019-05-11 23:16:32.734 debug running Interval0
moma.0 2019-05-11 23:16:32.734 debug running Once
moma.0 2019-05-11 23:16:32.687 debug creating moma instance entries
moma.0 2019-05-11 23:16:32.687 debug creating moma meta entries
moma.0 2019-05-11 23:16:32.672 debug starting adapter
moma.0 2019-05-11 23:16:32.672 info starting. Version 1.0.0 in C:/ioBroker/node_modules/iobroker.moma, node: v8.16.0
moma.0 2019-05-11 23:16:32.656 debug statesDB connected
moma.0 2019-05-11 23:16:32.656 debug objectDB connected
moma.0 2019-05-11 23:16:02.232 info cleaned everything up...
moma.0 2019-05-11 23:16:02.216 error at C:\ioBroker\node_modules\ws\lib\Receiver.js:349:10
moma.0 2019-05-11 23:16:02.216 error at Receiver.flush (C:\ioBroker\node_modules\ws\lib\Receiver.js:347:3)
moma.0 2019-05-11 23:16:02.216 error at C:\ioBroker\node_modules\ws\lib\Receiver.js:508:14
moma.0 2019-05-11 23:16:02.216 error at Receiver.applyExtensions (C:\ioBroker\node_modules\ws\lib\Receiver.js:371:5)
moma.0 2019-05-11 23:16:02.216 error at C:\ioBroker\node_modules\ws\lib\Receiver.js:538:11
moma.0 2019-05-11 23:16:02.216 error at C:\ioBroker\node_modules\ws\lib\Receiver.js:349:10
moma.0 2019-05-11 23:16:02.216 error at Receiver.flush (C:\ioBroker\node_modules\ws\lib\Receiver.js:347:3)
moma.0 2019-05-11 23:16:02.216 error at C:\ioBroker\node_modules\ws\lib\Receiver.js:508:14
moma.0 2019-05-11 23:16:02.216 error at Receiver.applyExtensions (C:\ioBroker\node_modules\ws\lib\Receiver.js:371:5)
moma.0 2019-05-11 23:16:02.216 error at C:\ioBroker\node_modules\ws\lib\Receiver.js:529:40
moma.0 2019-05-11 23:16:02.216 error RangeError: Maximum call stack size exceeded
moma.0 2019-05-11 23:16:02.216 error uncaught exception: Maximum call stack size exceeded
moma.0 2019-05-11 23:16:00.320 debug running Interval4
moma.0 2019-05-11 23:16:00.318 debug running Interval3
moma.0 2019-05-11 23:16:00.317 debug running Interval2
moma.0 2019-05-11 23:16:00.316 debug running Interval1
moma.0 2019-05-11 23:16:00.302 debug running Interval0
moma.0 2019-05-11 23:16:00.301 debug running Once
moma.0 2019-05-11 23:16:00.239 debug creating moma instance entries
moma.0 2019-05-11 23:16:00.239 debug creating moma meta entries
moma.0 2019-05-11 23:16:00.239 debug starting adapter
moma.0 2019-05-11 23:16:00.237 info starting. Version 1.0.0 in C:/ioBroker/node_modules/iobroker.moma, node: v8.16.0

`

AWhiteKnight commented 5 years ago

Wenn dann noch viele weitere Adapter dazukommen ist wohl die Anzahl asynchroner Requests zu hoch. Werde das bei mir mal stärker reglementieren.

AWhiteKnight commented 5 years ago

Habe gerade mit einem Verbund von 3 Maschinen die alles loggen einen Test mit 1.0.1 gemacht. Jetzt beschwert sich admin.0, immer wieder das zuviele Events kommen ;-) 1.0.1 ist jetzt im npm repository. Wenn keine Probleme auftauchen gebe ich das für stable frei

sigi2345 commented 5 years ago

Installiert, Fehler kommt noch immer. Ich habe mal sehr wenig angehakt. Ich glaube das es der Intervall 0 = 1 Sekunde ist.........ich teste weiter. Adapter bleibt ca. 1 Minute Rot, wird dann Grün. Das kommt jetzt ständig: `

moma.0 2019-05-12 11:55:52.548 info starting. Version 1.0.1 in C:/ioBroker/node_modules/iobroker.moma, node: v8.16.0
moma.0 2019-05-12 11:55:21.634 info cleaned everything up...
moma.0 2019-05-12 11:55:21.633 error at WS.Emitter.emit (C:\ioBroker\node_modules\engine.io-client\node_modules\component-emitter\index.js:133:20)
moma.0 2019-05-12 11:55:21.633 error at WS. (C:\ioBroker\node_modules\engine.io-client\lib\socket.js:268:10)
moma.0 2019-05-12 11:55:21.633 error at Socket.onPacket (C:\ioBroker\node_modules\engine.io-client\lib\socket.js:451:14)
moma.0 2019-05-12 11:55:21.633 error at Socket.Emitter.emit (C:\ioBroker\node_modules\engine.io-client\node_modules\component-emitter\index.js:133:20)
moma.0 2019-05-12 11:55:21.633 error at Socket. (C:\ioBroker\node_modules\component-bind\index.js:21:15)
moma.0 2019-05-12 11:55:21.633 error at Manager.ondata (C:\ioBroker\node_modules\socket.io-client\lib\manager.js:322:16)
moma.0 2019-05-12 11:55:21.633 error at Decoder.add (C:\ioBroker\node_modules\socket.io-parser\index.js:246:12)
moma.0 2019-05-12 11:55:21.633 error at Decoder.Emitter.emit (C:\ioBroker\node_modules\component-emitter\index.js:134:20)
moma.0 2019-05-12 11:55:21.633 error at Decoder. (C:\ioBroker\node_modules\component-bind\index.js:21:15)
moma.0 2019-05-12 11:55:21.633 error at Manager.ondecoded (C:\ioBroker\node_modules\socket.io-client\lib\manager.js:332:8)
moma.0 2019-05-12 11:55:21.633 error RangeError: Maximum call stack size exceeded
moma.0 2019-05-12 11:55:21.633 error uncaught exception: Maximum call stack size exceeded
moma.0 2019-05-12 11:55:20.778 info starting. Version 1.0.1 in C:/ioBroker/node_modules/iobroker.moma, node: v8.16.0

`

Screenshot (3160) Screenshot (3161)

AWhiteKnight commented 5 years ago

Was passiert, wenn der Adapter angehalten wird, alle States gelöscht werden (moma.x und moma.meta) und dann der Adapter neu gestartet wird? Ich habe den Typ von ein paar Variablen korrigiert, deshalb auch 1.0.0 ;-)

sigi2345 commented 5 years ago

Ok, habe ich gemacht, Adapter ist sofort Grün geworden. LOG:

`

moma.0 | 2019-05-12 14:15:30.589 | debug | running Interval0 moma.0 | 2019-05-12 14:15:29.579 | debug | running Interval0 moma.0 | 2019-05-12 14:15:28.577 | debug | running Interval0 moma.0 | 2019-05-12 14:15:27.575 | debug | running Interval0 moma.0 | 2019-05-12 14:15:26.574 | debug | running Interval0 moma.0 | 2019-05-12 14:15:25.563 | debug | running Interval0 moma.0 | 2019-05-12 14:15:24.559 | debug | running Interval0 moma.0 | 2019-05-12 14:15:24.499 | debug | running Interval1 moma.0 | 2019-05-12 14:15:23.556 | debug | running Interval0 moma.0 | 2019-05-12 14:15:22.553 | debug | running Interval0 moma.0 | 2019-05-12 14:15:21.551 | debug | running Interval0 moma.0 | 2019-05-12 14:15:20.548 | debug | running Interval0 moma.0 | 2019-05-12 14:15:19.546 | debug | running Interval0 moma.0 | 2019-05-12 14:15:18.528 | debug | running Interval0 moma.0 | 2019-05-12 14:15:17.520 | debug | running Interval0 moma.0 | 2019-05-12 14:15:16.518 | debug | running Interval0 moma.0 | 2019-05-12 14:15:15.516 | debug | running Interval0 moma.0 | 2019-05-12 14:15:14.500 | debug | running Interval0 moma.0 | 2019-05-12 14:15:14.497 | debug | running Interval1 moma.0 | 2019-05-12 14:15:14.496 | debug | running Interval2 moma.0 | 2019-05-12 14:15:14.495 | debug | running Interval3 moma.0 | 2019-05-12 14:15:14.494 | debug | running Interval4 moma.0 | 2019-05-12 14:15:14.489 | debug | running Once moma.0 | 2019-05-12 14:15:14.411 | debug | creating moma instance entries moma.0 | 2019-05-12 14:15:14.410 | debug | creating moma meta entries moma.0 | 2019-05-12 14:15:14.407 | debug | starting adapter moma.0 | 2019-05-12 14:15:14.397 | info | starting. Version 1.0.1 in C:/ioBroker/node_modules/iobroker.moma, node: v8.16.0 moma.0 | 2019-05-12 14:15:14.211 | debug | statesDB connected moma.0 | 2019-05-12 14:15:14.197 | debug | objectDB connected moma.0 | 2019-05-12 14:14:41.103 | error | exception by stop: Cannot read property 'info' of undefined moma.0 | 2019-05-12 14:14:41.103 | error | at Socket.onack (C:\ioBroker\node_modules\socket.io-client\lib\socket.js:312:9) moma.0 | 2019-05-12 14:14:41.103 | error | at Socket.that.states.setState (C:\ioBroker\node_modules\iobroker.js-controller\lib\adapter.js:5123:121) moma.0 | 2019-05-12 14:14:41.103 | error | at process.exit (internal/process.js:146:15) moma.0 | 2019-05-12 14:14:41.103 | error | at process.emit (events.js:211:7) moma.0 | 2019-05-12 14:14:41.103 | error | at emitOne (events.js:116:13) moma.0 | 2019-05-12 14:14:41.103 | error | at Object.onceWrapper (events.js:315:30) moma.0 | 2019-05-12 14:14:41.103 | error | at process.stop (C:\ioBroker\node_modules\iobroker.js-controller\lib\adapter.js:5350:18) moma.0 | 2019-05-12 14:14:41.103 | error | at Moma.emit (events.js:211:7) moma.0 | 2019-05-12 14:14:41.103 | error | at emitOne (events.js:116:13) moma.0 | 2019-05-12 14:14:41.103 | error | at Moma.onUnload (C:\ioBroker\node_modules\iobroker.moma\main.js:223:13) moma.0 | 2019-05-12 14:14:41.103 | error | TypeError: Cannot read property 'info' of undefined moma.0 | 2019-05-12 14:14:41.103 | error | uncaught exception: Cannot read property 'info' of undefined moma.0 | 2019-05-12 14:14:41.050 | error | adapter disabled moma.0 | 2019-05-12 14:14:41.035 | debug | statesDB connected moma.0 | 2019-05-12 14:14:41.018 | debug | objectDB connected

` OK, nach einen Neustart kommt wieder der Fehler:

`

       
javascript.0 2019-05-12 14:32:00.128 info script.js.Sigi.UWZ: []
host.Medion 2019-05-12 14:31:49.353 info Restart adapter system.adapter.moma.0 because enabled
host.Medion 2019-05-12 14:31:49.353 error instance system.adapter.moma.0 terminated with code 0 (OK)
host.Medion 2019-05-12 14:31:49.353 error Caught by controller[0]: at WS.Emitter.emit (C:\ioBroker\node_modules\engine.io-client\node_modules\component-emitter\index.js:133:20)
host.Medion 2019-05-12 14:31:49.353 error Caught by controller[0]: at WS. (C:\ioBroker\node_modules\engine.io-client\lib\socket.js:268:10)
host.Medion 2019-05-12 14:31:49.353 error Caught by controller[0]: at Socket.onPacket (C:\ioBroker\node_modules\engine.io-client\lib\socket.js:451:14)
host.Medion 2019-05-12 14:31:49.353 error Caught by controller[0]: at Socket.Emitter.emit (C:\ioBroker\node_modules\engine.io-client\node_modules\component-emitter\index.js:133:20)
host.Medion 2019-05-12 14:31:49.352 error Caught by controller[0]: at Socket. (C:\ioBroker\node_modules\component-bind\index.js:21:15)
host.Medion 2019-05-12 14:31:49.352 error Caught by controller[0]: at Manager.ondata (C:\ioBroker\node_modules\socket.io-client\lib\manager.js:322:16)
host.Medion 2019-05-12 14:31:49.352 error Caught by controller[0]: at Decoder.add (C:\ioBroker\node_modules\socket.io-parser\index.js:246:12)
host.Medion 2019-05-12 14:31:49.352 error Caught by controller[0]: at Decoder.Emitter.emit (C:\ioBroker\node_modules\component-emitter\index.js:134:20)
host.Medion 2019-05-12 14:31:49.352 error Caught by controller[0]: at Decoder. (C:\ioBroker\node_modules\component-bind\index.js:21:15)
host.Medion 2019-05-12 14:31:49.352 error Caught by controller[0]: at Manager.ondecoded (C:\ioBroker\node_modules\socket.io-client\lib\manager.js:332:8)
host.Medion 2019-05-12 14:31:49.352 error Caught by controller[0]: RangeError: Maximum call stack size exceeded
moma.0 2019-05-12 14:31:49.105 debug running Interval0
moma.0 2019-05-12 14:31:48.471 debug running Interval0
moma.0 2019-05-12 14:31:48.471 debug running Interval1
moma.0 2019-05-12 14:31:48.471 debug running Interval2
moma.0 2019-05-12 14:31:48.471 debug running Interval3
moma.0 2019-05-12 14:31:48.471 debug running Interval4
moma.0 2019-05-12 14:31:48.471 debug running Once
moma.0 2019-05-12 14:31:48.471 debug creating moma instance entries
moma.0 2019-05-12 14:31:48.471 debug creating moma meta entries
moma.0 2019-05-12 14:31:48.471 debug starting adapter
moma.0 2019-05-12 14:31:48.471 info starting. Version 1.0.1 in C:/ioBroker/node_modules/iobroker.moma, node: v8.16.0

`

`

AWhiteKnight commented 5 years ago

Ok, hab mal in die socket.js reingeschaut, die in dem stack-trace angesprochen wird. Offensichtlich habe ich eine andere Version im Einsatz - Zeile 268 ist bei mir leer und 451 gibt es gar nicht mehr ;-) Bei mir ist das aktuelle "latest" und das aktuelle "stable" Repository auf den Rechnern installiert.

AWhiteKnight commented 5 years ago

Den Fehler "uncaught exception: Cannot read property 'info' of undefined" habe ich lokalisiert. Kommt beim Aufräumen wegen einer beim auskommentieren übersehenen Log-Meldung :-(

sigi2345 commented 5 years ago

Info: Bei mir ist alles auf "latest"

ioBroker.js-controller 1.5.8

AWhiteKnight commented 5 years ago

Hmm, dann ist da möglicherweise was windows-spezifisches im Spiel. Bei mir laufen in der Linux-Prozessübersicht anfangs einige io.moma Prozesse auf, die aber dann sukzessive abgearbeitet werden. Ich habe mal einen anderen Lösungsansatz umgesetzt: Nach Anlegen der Variablen und Start eines jeden Intervall jeweils kurz warten. Dadurch dauert der Start etwas länger, aber es laufen bei mir keine Prozesse mehr auf. Vielleicht klappt es so.

sigi2345 commented 5 years ago

Hallo, nach Start des Adapters oder bei jeder Veränderung kommt wieder der Fehler: (Dieser ist aber nach ca. 2-5 Minuten weg) `

host.Medion 2019-05-14 09:30:37.406 info Restart adapter system.adapter.moma.0 because enabled
host.Medion 2019-05-14 09:30:37.406 error instance system.adapter.moma.0 terminated with code 0 (OK)
host.Medion 2019-05-14 09:30:37.406 error Caught by controller[0]: at WS.Transport.onPacket (C:\ioBroker\node_modules\engine.io-client\lib\transport.js:145:8)
host.Medion 2019-05-14 09:30:37.406 error Caught by controller[0]: at WS.Emitter.emit (C:\ioBroker\node_modules\engine.io-client\node_modules\component-emitter\index.js:133:20)
host.Medion 2019-05-14 09:30:37.406 error Caught by controller[0]: at WS. (C:\ioBroker\node_modules\engine.io-client\lib\socket.js:268:10)
host.Medion 2019-05-14 09:30:37.406 error Caught by controller[0]: at Socket.onPacket (C:\ioBroker\node_modules\engine.io-client\lib\socket.js:451:14)
host.Medion 2019-05-14 09:30:37.406 error Caught by controller[0]: at Socket.Emitter.emit (C:\ioBroker\node_modules\engine.io-client\node_modules\component-emitter\index.js:133:20)
host.Medion 2019-05-14 09:30:37.406 error Caught by controller[0]: at Socket. (C:\ioBroker\node_modules\component-bind\index.js:21:15)
host.Medion 2019-05-14 09:30:37.406 error Caught by controller[0]: at Manager.ondata (C:\ioBroker\node_modules\socket.io-client\lib\manager.js:322:16)
host.Medion 2019-05-14 09:30:37.406 error Caught by controller[0]: at Decoder.add (C:\ioBroker\node_modules\socket.io-parser\index.js:237:14)
host.Medion 2019-05-14 09:30:37.405 error Caught by controller[0]: at decodeString (C:\ioBroker\node_modules\socket.io-parser\index.js:325:9)
host.Medion 2019-05-14 09:30:37.405 error Caught by controller[0]: at tryParse (C:\ioBroker\node_modules\socket.io-parser\index.js:336:12)
host.Medion 2019-05-14 09:30:37.405 error Caught by controller[0]: RangeError: Maximum call stack size exceeded
moma.0 2019-05-14 09:30:35.070 info starting. Version 1.0.2 in C:/ioBroker/node_modules/iobroker.moma, node: v8.16.0

`

AWhiteKnight commented 5 years ago

Ok, wir kommen der Sache näher. Wenn alles geloggt wird, werden am Anfang haufenweise States angelegt. Das führt zu extrem vielen Messages/Ereignissen im IoBroker, vor allem wenn sich andere auch noch auf diese States subscriben oder die States gespeichert werden. Ich habe bei mir regelmäßig über 9000 Ereignisse in der Anzeige im Host-Tab gesehen. Damit wird der Messaging-Mechanismus, insbesondere der socket.io Stack wohl hoffnungslos überfordert. Muss das ganze also noch weiter entzerren. Für den Anfang genügt es vielleicht das Intervall 0 auf 2 oder 3 Sekunden hochzusetzen.

AWhiteKnight commented 5 years ago

1.0.3 erzeugt deutlich weniger "Ereignisse".

sigi2345 commented 5 years ago

Version 1.0.3 installiert, Fehler ist noch da. Also wie gesagt dauert beim Neustarten des Adapters das Ganze ca. 3 Minuten erst dann wird er Grün. Wenn ich die Sekunden von 1 auf 2 oder höher einstelle ändert sich nichts.

AWhiteKnight commented 5 years ago

Was zeigt der Tab "Instanzen" im Expertenmodus in der Spalte "Ereignisse" für den Adapter an? Gibt es einen weiteren Adapter, der eine hohe Ereigniszahl anzeigt? Wenn ja welcher? Ich will die Situation exakter nachzustellen.

sigi2345 commented 5 years ago

Screenshot (3190)

sigi2345 commented 5 years ago

Adapter mit Hohen Ereignissen:

history.0 1064 / 10
javascript.0 946 / 17 admin.0 435 / 10

Screenshot (3193) Screenshot (3194)

AWhiteKnight commented 5 years ago

Das sieht für mich so aus, als ob alle States in die History gespeichert werden und der JavaScript Adapter sich auf alle States beim Start subscribed oder? Habe einen möglichen Übeltäter gefunden: Die networkConnections im Intervall 0 - werde daraus eine HTML-Tabelle machen analog der processes.list . Letztere werde ich auch zu einer hübscheren Tabelle mit Kopfzeile umarbeiten.

sigi2345 commented 5 years ago

`

host.Medion 2019-05-20 18:18:49.377 info Restart adapter system.adapter.moma.0 because enabled
host.Medion 2019-05-20 18:18:49.377 error instance system.adapter.moma.0 terminated with code 0 (OK)
host.Medion 2019-05-20 18:18:49.377 error Caught by controller[0]: at WS.Transport.onPacket (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\engine.io-client\lib\transport.js:145:8)
host.Medion 2019-05-20 18:18:49.377 error Caught by controller[0]: at WS.Emitter.emit (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\engine.io-client\node_modules\component-emitter\index.js:133:20)
host.Medion 2019-05-20 18:18:49.377 error Caught by controller[0]: at WS. (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\engine.io-client\lib\socket.js:268:10)
host.Medion 2019-05-20 18:18:49.377 error Caught by controller[0]: at Socket.onPacket (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\engine.io-client\lib\socket.js:451:14)
host.Medion 2019-05-20 18:18:49.377 error Caught by controller[0]: at Socket.Emitter.emit (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\engine.io-client\node_modules\component-emitter\index.js:133:20)
host.Medion 2019-05-20 18:18:49.377 error Caught by controller[0]: at Socket. (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\component-bind\index.js:21:15)
host.Medion 2019-05-20 18:18:49.377 error Caught by controller[0]: at Manager.ondata (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\socket.io-client\lib\manager.js:322:16)
host.Medion 2019-05-20 18:18:49.377 error Caught by controller[0]: at Decoder.add (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\socket.io-parser\index.js:237:14)
host.Medion 2019-05-20 18:18:49.377 error Caught by controller[0]: at decodeString (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\socket.io-parser\index.js:325:9)
host.Medion 2019-05-20 18:18:49.376 error Caught by controller[0]: at tryParse (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\socket.io-parser\index.js:336:12)
host.Medion 2019-05-20 18:18:49.376 error Caught by controller[0]: RangeError: Maximum call stack size exceeded
moma.0 2019-05-20 18:18:49.357 info cleaned everything up...
moma.0 2019-05-20 18:18:49.357 error RangeError: Maximum call stack size exceeded at tryParse (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\socket.io-parser\index.js:336:12) at decodeString (C:\ioBroker\node_modul
moma.0 2019-05-20 18:18:49.356 error uncaught exception: Maximum call stack size exceeded
moma.0 2019-05-20 18:18:48.924 debug creating moma instance entries
moma.0 2019-05-20 18:18:48.426 debug creating moma meta entries
moma.0 2019-05-20 18:18:48.426 debug release preparation
moma.0 2019-05-20 18:18:48.426 debug starting adapter
moma.0 2019-05-20 18:18:48.426 info starting. Version 1.1.0 in C:/ioBroker/node_modules/iobroker.moma, node: v8.16.0
heatingcontrol.0 2019-05-20 18:18:48.388 debug system.adapter.admin.0: logging true
host.Medion 2019-05-20 18:18:48.035 info instance system.adapter.moma.0 started with pid 11148
host.Medion 2019-05-20 18:18:17.988 info Restart adapter system.adapter.moma.0 because enabled

`

sigi2345 commented 5 years ago

Schade, bekomme den Fehler einfach immer wieder. Also wenn ich den Adapter lösche und neu installiere, dann funktioniert er. Sobald ich was ändere, kommen dann die Fehler. Auch wenn ich 10 Minuten warte erholt er sich irgendwann.

AWhiteKnight commented 5 years ago

Ich versuche mal einen Windows-Rechner aufzutreiben, mit dem ich das nachstellen kann.

AWhiteKnight commented 5 years ago

Version 1.1.2 loggt im silly-mode sehr ausführlich welcher Aufruf gerade ausgeführt wird. Bitte mal ein Log-File erstellen und mir zukommen lassen.

sigi2345 commented 5 years ago

Version 1.1.2 loggt im silly-mode sehr ausführlich welcher Aufruf gerade ausgeführt wird. Bitte mal ein Log-File erstellen und mir zukommen lassen.

iobroker.2019-06-05 - Kopie (2).log

AWhiteKnight commented 5 years ago

Danke, das Problem scheint bei den networkConnections aufzutreten Die Reihenfolge sollte sein (Ausgabe erfolgt vor Ausführung): networkConnections currentLoad processes Bitte mal der Reihe nach diese Features deaktivieren. Ab welchem tritt der Fehler dann nicht mehr auf?

sigi2345 commented 5 years ago

Danke, das Problem scheint bei den networkConnections aufzutreten Die Reihenfolge sollte sein (Ausgabe erfolgt vor Ausführung): networkConnections currentLoad processes Bitte mal der Reihe nach diese Features deaktivieren. Ab welchem tritt der Fehler dann nicht mehr auf?

Fehler noch da......... iobroker.2019-06-05 - Kopie (3).log

AWhiteKnight commented 5 years ago

Also falsche Spur. Was passiert, wenn alle Features aktiviert sind, aber das Intervall0 deaktiviert ist?

Mein eigener Win10 Rechner hat leider keine Probleme mit dem CallStack, läuft seit Tagen problemlos.

sigi2345 commented 5 years ago

Also falsche Spur. Was passiert, wenn alle Features aktiviert sind, aber das Intervall0 deaktiviert ist?

Mein eigener Win10 Rechner hat leider keine Probleme mit dem CallStack, läuft seit Tagen problemlos.

Aha, dann wird er sofort Grün!

AWhiteKnight commented 5 years ago

Wie sieht es aus, wenn die Intervalldauer > 2 sek gewählt wird?

sigi2345 commented 5 years ago

Wie sieht es aus, wenn die Intervalldauer > 2 sek gewählt wird?

Rot......... Du kannst gerne per TeamViewer mal reinsehen.

AWhiteKnight commented 5 years ago

Danke für das Angebot. Benutze selber kein TeamViewer sondern Anydesk. Müsste ich also gegebenenfalls aus dem Büro machen. Dort nutzen wir TeamViewer. Aber ich glaube das es wenig hilft. Soweit ich das jetzt verstehe gehört der Fehler typischerweise zu einer Rekursion ohne Abbruch. Da hier keine Rekursion stattfindet sondern mit Timern gearbeitet wird, ist das Problem nicht so eindeutig im Code lokalisierbar. Letzter Test: Was passiert bei Intervall 0 = 1 Sek und keines der Features ist aktiviert?

AWhiteKnight commented 5 years ago

Denkbar wäre auch, dass bereits so viele Dinge in der node-Instanz laufen, dass der Call-Stack schon voll ist und moma nur das Faß zum überlaufen bringt. Dazu weis ich zu wenig von der internen Arbeitsweise von node und iobroker.

AWhiteKnight commented 5 years ago

Tritt der Fehler mit der Version 1.2.1 noch auf?

sigi2345 commented 5 years ago

Tritt der Fehler mit der Version 1.2.1 noch auf?

`

moma.0 2019-08-13 09:30:07.430 info terminating
moma.0 2019-08-13 09:30:07.423 info cleaned everything up...
moma.0 2019-08-13 09:30:07.423 error at Receiver.applyExtensions (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\ws\lib\Receiver.js:371:5)
moma.0 2019-08-13 09:30:07.423 error at C:\ioBroker\node_modules\iobroker.js-controller\node_modules\ws\lib\Receiver.js:536:18
moma.0 2019-08-13 09:30:07.423 error at Receiver.ontext (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\ws\lib\WebSocket.js:841:10)
moma.0 2019-08-13 09:30:07.423 error at WebSocket.emit (events.js:198:13)
moma.0 2019-08-13 09:30:07.423 error at WebSocket.onMessage (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\ws\lib\WebSocket.js:442:14)
moma.0 2019-08-13 09:30:07.423 error at WebSocket.ws.onmessage (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\engine.io-client\lib\transports\websocket.js:146:10)
moma.0 2019-08-13 09:30:07.423 error at WS.Transport.onData (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\engine.io-client\lib\transport.js:137:8)
moma.0 2019-08-13 09:30:07.423 error at WS.Transport.onPacket (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\engine.io-client\lib\transport.js:145:8)
moma.0 2019-08-13 09:30:07.423 error at WS.Emitter.emit (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\engine.io-client\node_modules\component-emitter\index.js:133:20)
moma.0 2019-08-13 09:30:07.423 error at WS. (C:\ioBroker\node_modules\iobroker.js-controller\node_modules\engine.io-client\lib\socket.js:268:10)
moma.0 2019-08-13 09:30:07.423 error RangeError: Maximum call stack size exceeded
moma.0 2019-08-13 09:30:07.423 error uncaught exception: Maximum call stack size exceeded
moma.0 2019-08-13 09:30:06.770 debug system.adapter.admin.0: logging true
moma.0 2019-08-13 09:30:03.003 info library systeminformation version 4.14.4
moma.0 2019-08-13 09:30:02.358 debug starting IntervalAlive
moma.0 2019-08-13 09:30:02.352 debug creating moma instance entries
moma.0 2019-08-13 09:30:02.351 debug creating moma meta entries
moma.0 2019-08-13 09:30:02.350 debug release preparation
moma.0 2019-08-13 09:30:02.341 debug starting adapter
moma.0 2019-08-13 09:30:02.193 info starting. Version 1.2.1 in C:/ioBroker/node_modules/iobroker.moma, node: v10.16.0
moma.0 2019-08-13 09:30:01.860 debug statesDB connected
moma.0 2019-08-13 09:30:01.738 debug objectDB connected
moma.0 2019-08-13 09:27:51.007 info library systeminformation version 4.14.4
moma.0 2019-08-13 09:27:50.715 info starting. Version 1.2.1 in C:/ioBroker/node_modules/iobroker.moma, node: v10.16.0

`

Auch keine Werte bei Speed und Temperatur............

Screenshot (3870)

AWhiteKnight commented 5 years ago

check bitte mal, ob noch alle Häkchen in den Einstellungen gesetzt sind.

sigi2345 commented 5 years ago

check bitte mal, ob noch alle Häkchen in den Einstellungen gesetzt sind.

Ja..........

AWhiteKnight commented 4 years ago

So weit wie möglich entschärft. Allerdings ist es wohl möglich, die Nachrichten-Schlange im iobroker gnadelos zu überlasten durch entsprechend viele Ereignisse. Abhilfe: den Javascript-Adapter nicht alle States beim Start abonnieren lassen, Monitoring -Intervall 0 auf >=2 Sekunden hochsetzen