[GELÖST] stack overflow in PushVar [iseESPexec.cpp:166]

[Black]

seit heute habe ich folgenede Meldung in meinem Syslog und weiss nicht, woher der kommen soll.

An IOBroker liegts nicht, wenn ich den runterfahre kommt das auch.

Code: Alles auswählen

ec 22 17:00:00 homematic-raspi cron.info crond[210]: USER root pid 4896 cmd /bin/SetInterfaceClock 127.0.0.1:2001
Dec 22 17:00:00 homematic-raspi cron.info crond[210]: USER root pid 4897 cmd /bin/setHWClock.sh
Dec 22 17:30:00 homematic-raspi cron.info crond[210]: USER root pid 13548 cmd /bin/SetInterfaceClock 127.0.0.1:2001
Dec 22 18:00:00 homematic-raspi cron.info crond[210]: USER root pid 22031 cmd /bin/SetInterfaceClock 127.0.0.1:2001
Dec 22 18:00:00 homematic-raspi cron.info crond[210]: USER root pid 22032 cmd /bin/setHWClock.sh
Dec 22 18:30:00 homematic-raspi cron.info crond[210]: USER root pid 30541 cmd /bin/SetInterfaceClock 127.0.0.1:2001
Dec 22 19:00:00 homematic-raspi cron.info crond[210]: USER root pid 6746 cmd /bin/SetInterfaceClock 127.0.0.1:2001
Dec 22 19:00:00 homematic-raspi cron.info crond[210]: USER root pid 6747 cmd /bin/setHWClock.sh
Dec 22 19:20:47 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:23:15 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:25:29 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:27:28 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:30:00 homematic-raspi cron.info crond[210]: USER root pid 15250 cmd /bin/SetInterfaceClock 127.0.0.1:2001
Dec 22 19:30:16 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:30:39 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:30:39 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:30:39 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:30:39 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:30:39 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:30:39 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:32:50 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:35:10 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:37:16 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:40:10 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:42:50 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:45:16 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:47:27 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:49:25 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:52:11 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:54:43 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:57:01 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 19:59:05 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]

Es lieg eigentlich so nix vor.... vllt hat Jens ja ne Idee ?

laufen tuts wohl alles... ich seh zumindest keineFehlfunktion

Firmware: 2.29.23.20171118

[Black]

so... nu wirds richtig krumm... bin da ein wenig mit dem logfile rangegangen:

Code: Alles auswählen

Dec 22 20:17:23 homematic-raspi local0.info ReGaHss: Info: IseDOMPrg::SetState: Program ID = 4149 'prgEnergyCounter_4138_MEQ0706025:1', rule's trigger flags = 6 [iseDOMPrg.cpp:515]
Dec 22 20:17:23 homematic-raspi local0.info ReGaHss: Info: SchedulerRT::Add: added script element - id= 4151; time= 0 [iseRTScheduler.cpp:343]
Dec 22 20:17:23 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 20:17:23 homematic-raspi local0.info ReGaHss: Info: SchedulerRT::ThreadFunction(): wait for event ms= 4294967295 [iseRTScheduler.cpp:481]

unmittelbar vorher wurde immer das sysinterne Programm vom Energiezähler aufgerufen...

da hatte ich mal etwas dran manipiliert, was aber eigentlich keinenG rund liefern sollte:

Code: Alles auswählen

!- Stromzähler
!- Modifiziertes Original Script
object oSRC  = dom.GetObject ("$src$");
if (oSRC) {

das war eingefügt, um sicherzustellen, das das Script nur abgearbeitet wird, wenn es durch Trigger ausgelöst wird und nicht durch Boot CCU. Grund war ein manchmal Auftretendes Fehlrechnen des Energiezählerz beim Booten.

so, nun Frag ich mich, wieso dieses Konstruct einen Stack Overflow wirft...

wo ich das Konstruct wieder rausgenommen habe ist Ruhe....

black

[jmaus]

Wie genau sieht denn das Originalskript an der Stelle aus?

[Black]

Code: Alles auswählen

object chn   = dom.GetObject('4138');
object oBoot = chn.DPByControl('POWERMETER_IGL.BOOT');
object oEnergyCounter = chn.DPByControl('POWERMETER_IGL.ENERGY_COUNTER');
	object oSysVarEnergyCounter = dom.GetObject('svEnergyCounter_4138_MEQ0706025:1');
	object oSysVarEnergyCounterOldVal = dom.GetObject('svEnergyCounterOldVal_4138');
	object oSysVarEnergyCounterResetPressed = dom.GetObject('svEnergyCounter_4138_MEQ0706025:1_RESET');
	object oSysVarEnergyCounterTmpOldVal = dom.GetObject('svEnergyCounter_4138_MEQ0706025:1_TMP_OLDVAL');
	object oSysVarEnergyCounterDeviceReset = dom.GetObject('svEnergyCounter_4138_MEQ0706025:1_DEVICE_RESET');
	boolean bootFlag = oBoot.Value();
	real devVal = oEnergyCounter.Value();
	real devValMax = oEnergyCounter.ValueMax();
	real oldDevVal = oSysVarEnergyCounterOldVal.Value();
	real tmpOldDevVal = oSysVarEnergyCounterTmpOldVal.Value();
	integer ioldDevVal = (tmpOldDevVal.ToString().ToFloat() * 100000).ToInteger();
	real diffVal = 0.0;
	real sysVarVal = oSysVarEnergyCounter.Value();
	integer tmp_devVal = (devVal.ToString().ToFloat() * 100000).ToInteger();
	integer tmp_oldDevVal = (oldDevVal.ToString().ToFloat() * 100000).ToInteger();


	if ( oBoot.Value() == true ) {
		oSysVarEnergyCounterDeviceReset.State(true);
		if (ioldDevVal <= 0) {oSysVarEnergyCounter.State(0);}
	} else {
		boolean resetPressed = oSysVarEnergyCounterResetPressed.Value();
		!- boolean devReset = oSysVarEnergyCounterDeviceReset.Value();
		if ( (resetPressed == true) && (oSysVarEnergyCounterDeviceReset.Value() == true) ) {
			oSysVarEnergyCounterTmpOldVal.State(0);
			tmpOldDevVal = 0;
		}
		!-Normales Hochzaehlen. Geraetwert > vorheriger Wert
		if ((tmp_devVal >= tmp_oldDevVal) && (oSysVarEnergyCounterDeviceReset.Value() == false)) {
			if (resetPressed == false) {
				diffVal = oEnergyCounter.Value() - oldDevVal;
			} else {
			!-Reset pressed
				diffVal = oEnergyCounter.Value() - tmpOldDevVal;
				if ((diffVal.ToString().ToFloat() * 100000).ToInteger() < 0 ) {diffVal = oEnergyCounter.Value();}
				oSysVarEnergyCounterResetPressed.State(0);
			}
		} else {
			!-Geraetewert ist kleiner vorheriger Wert
			!-Entweder Ueberlauf, oder Batterietausch
			if (oSysVarEnergyCounterDeviceReset.Value() == false) {
				!-Normaler Geraeteueberlauf
				if(tmp_devVal > 0) {
				diffVal = (oEnergyCounter.Value() + devValMax) - oldDevVal;
				}
			} else {
				!-Zaehle Geraetewert zum CCU-Zaehler
				diffVal = oEnergyCounter.Value();
				if ((diffVal.ToString().ToFloat() * 100000).ToInteger() == 0) {
					oSysVarEnergyCounterDeviceReset.State(true);
				} else {
					oSysVarEnergyCounterDeviceReset.State(false);
				}
			}
		}
		!-Erhoehe den CCU-Zaehler
	oSysVarEnergyCounter.State(sysVarVal + diffVal);
	oSysVarEnergyCounterOldVal.State(oEnergyCounter.Value());
	oSysVarEnergyCounterTmpOldVal.State(oEnergyCounter.Value());

ich hab das ganze dann nur in den If block gekapselt gehabt. das war das original script, welches beim anlernen des Zählers automatisch angelegt wird.

Zu früh gefreut:

Code: Alles auswählen

Dec 22 21:00:00 homematic-raspi cron.info crond[210]: USER root pid 9410 cmd /bin/setHWClock.sh
Dec 22 21:00:39 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 21:00:39 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 21:00:39 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 21:00:39 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 21:00:39 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]
Dec 22 21:00:39 homematic-raspi local0.err ReGaHss: Error: stack overflow in PushVar [iseESPexec.cpp:166]

nun gleich 6 mal in einer sekunde... grmmlzzz

[Black]

hallo Jens, ich muss das Thema nochmal nach oben holen...

den Efekt hatte ich heute nochmal... und ich hab da einen verdacht, was es sein könnte. Die Info die du mit im pv geschrieben hattest war hilfreich da vllt etwas Licht ins dunkel zu werfen.

Sag mitmal bitte, wenn ein Progrmm getriggert wird,

legt (irgend eine Routine) via Pushvar die Variablen, die in den Bedingungen WENN etc stehen, auf einen Stackpointer. dann werden die Bedingungen auf erfüllt oder eben nicht geprüft.

Wenn diese via PushVar abgelegten Variablen wieder runtergeholt werden, ist alles ok. nur das scheint ja reproduzierbar daneben zu gehen manchmal.
wann werden diese wieder von Stack geholt, wenn die rega? irgendeine Subroutine Davon ? den DANN Teil des Programmes analysiert ? nun die entscheidende frage, was würde dann passieren, wenn das Programm keinen DANN Teil, sondern nur einen SONST Teil hat ? Läuft dann auch ein PopVar ?

Gruss, Black

[Black]

hi Jens,

ist der gebrachte Bugfix

"Bugfix: ReGa – Automatische Erweiterung des Variablenstacks umgesetzt um bei einer Abarbeitung von vielen genutzten Variablen nicht zu einem stack overflow zu kommen. "

eine folge der geschichte hier ?

danke und Gruss, black

[jmaus]

ist der gebrachte Bugfix

"Bugfix: ReGa – Automatische Erweiterung des Variablenstacks umgesetzt um bei einer Abarbeitung von vielen genutzten Variablen nicht zu einem stack overflow zu kommen. "

eine folge der geschichte hier ?

In der Tat. Also bitte testen, testen, testen!

[jmaus]

FYI: Heute habe ich die neue 2.31.25.20180120 herausgebracht. Diese sollte das hier gemeldete Problem hoffentlich komplett beheben.

[Black]

Vielen Danke jens,

ich hab grade meine Test Raspimatik mit der neuen version geupdatet und mal ein backup von den programmen eingespielt, die mich geärgert hatten..

Bis jetzt ohne Befund, lassen wir die mal 24 stunden rundlaufen nun, in den ersten Minuten / Stunden nach dem Neustart gings auch damals gut.

Ich geb rückmeldung sagen wir mal am Montag.

Black

[Black]

so, nochmal kurzes feedback.

auf dem testraspi trat das problem nun in der letzten Woche nicht mehr auf.
Ich betrachte es nun mal auch als gelöst.

Danke und greetz, Black