Hilfe erbeten: FHEM beendet sich selbst nach Update

Begonnen von netwalk, 21 April 2016, 12:40:30

Vorheriges Thema - Nächstes Thema

netwalk

Heute habe ich nach zwei/drei Wochen mal wieder ein Update gestartet. Es lief problemlos durch, jedoch beendet sich FHEM nach dem obligatorischen Neustart nach einigen Sekunden von selbst.
Wird der Dienst manuell über Putty gestartet, geschieht das gleiche.

Nun habe ich danach das vorher angelegte Backup zurückgespielt und wahlfrei nur ein Modul aktualisiert (z.B. 59_Weather.pm oder 10_KNX.pm).
Der Fehler bleibt: Update läuft durch, nach Neustart beendet sich FHEM von selbst.

Zur Fehlerverfolgung habe ich vorher den Verbose-Level auf 5 gestellt, werde aber von der Flut der nun auflaufenden Meldungen erschlagen. Der letzte Eintrag im Logfile ist immer verschieden, so dass ich nicht ein bestimmtes Modul als Fehlergrund ausmachen kann. Ich sehe nur, dass nach dem Update der Server normal startet und nach einigen Sekunden das Logfile abbricht (wenn der Dienst beendet wird).

Hat dieses Problem noch jemand, und wie gehe ich zur Fehlereingrenzung weiter vor?


2016.04.21 12:15:59 5: Cmd: >{Log('1','update finished, "shutdown restart" is needed to activate the changes.')}<
2016.04.21 12:15:59 1: update finished, "shutdown restart" is needed to activate the changes.
...
2016.04.21 12:18:06 0: Server started with 481 defined entities (fhem.pl:11267/2016-04-17 perl:5.014002 os:linux user:fhem pid:25283)


Die letzten Einträge im Logfile bei den einzelnen Versuchen waren z.B.:


2016.04.21 11:45:31 5: Triggering FritzBox (70 changes)
2016.04.21 11:45:31 5: Starting notify loop for FritzBox, first event tam1_oldMsg: 0
2016.04.21 11:45:31 5: rg.Temperature: not on any display, ignoring notify
Undefined subroutine &main::YahooWeatherAPI_RetrieveDataWithCache called at ./FHEM/59_Weather.pm line 122.

oder:

2016.04.21 12:18:52 5: Starting notify loop for sysmon, first event fs_root: Total: 29836 MB, Used: 4791 MB, 17 %, Available: 23784 MB at /
2016.04.21 12:18:52 5: rg.Temperature: not on any display, ignoring notify
2016.04.21 12:18:52 5: EDIPLUG: GetUpdate
2016.04.21 12:18:52 4: HttpUtils url=http://admin:1234@192.168.10.152:10000/smartplug.cgi
2016.04.21 12:18:52 4: http://admin:1234@192.168.10.150:10000/smartplug.cgi: HTTP response code 200
2016.04.21 12:18:52 4: HttpUtils http://admin:1234@192.168.10.150:10000/smartplug.cgi: Got data, length: 149
2016.04.21 12:18:52 5: 20_WLAN01 , Read : HASH(0x21d1348) , <?xml version="1.0" encoding="UTF8"?><SMARTPLUG id="edimax"><CMD id="get"><Device.System.Power.State>ON</Device.System.Power.State></CMD></SMARTPLUG>

No value specified for 'ForceArray' option in call to XMLin() at ./FHEM/98_EDIPLUG.pm line 223

live long and prosper
netwalk
_______________________________________________
INTEL NUC7CJYH, Homematic mit 3x HMLGW, JEELINK mit 18x TX29-DTH-IT, DUOFERNSTICK, FB7690 mit FBDECT, NETATMO, Philips HUE, RFXtrx433, 4x Reolink RLC-81MA

CoolTux

Schau mal ob Du unter fhem/FHEM/ eine Datei Namens YahooWeatherAPI.pm hast. Wenn ja schaue ob die Rechte die selben wie bei den anderen Dateien sind.
Du musst nicht wissen wie es geht! Du musst nur wissen wo es steht, wie es geht.
Support me to buy new test hardware for development: https://www.paypal.com/paypalme/MOldenburg
My FHEM Git: https://git.cooltux.net/FHEM/
Das TuxNet Wiki:
https://www.cooltux.net

netwalk

Ja, da ist folgende Datei:

YahooWeatherAPI.pm     10.485B     29.03.2016 08:57:00     rw-r--r--     fhem     dialout

Hat die gleichen Rechte wie die meisten anderen Module.
live long and prosper
netwalk
_______________________________________________
INTEL NUC7CJYH, Homematic mit 3x HMLGW, JEELINK mit 18x TX29-DTH-IT, DUOFERNSTICK, FB7690 mit FBDECT, NETATMO, Philips HUE, RFXtrx433, 4x Reolink RLC-81MA

CoolTux

Sicher das fhem beendet ist


ps ax | grep fhem


Zeig mal die Ausgabe nach einem

/etc/init.d/fhem stop

bisschen warten

/etc/init.d/fhem start
Du musst nicht wissen wie es geht! Du musst nur wissen wo es steht, wie es geht.
Support me to buy new test hardware for development: https://www.paypal.com/paypalme/MOldenburg
My FHEM Git: https://git.cooltux.net/FHEM/
Das TuxNet Wiki:
https://www.cooltux.net

netwalk

Hier sind die Ausgaben:

Eine Minute nach dem Update und Restart:

pi@RasPi ~ $ ps ax | grep fhem
28828 pts/0    S+     0:00 grep --color=auto fhem


Nächste Eingabe:


pi@RasPi ~ $ sudo /etc/init.d/fhem stop
Stopping fhem...
Can't connect to localhost:7072


Heißt doch, dass FHEM nicht mehr läuft, oder? Im "Top" sehe ich auch kein FHEM mehr...

Nächste Aktion:

pi@RasPi ~ $ sudo /etc/init.d/fhem start
Starting fhem...
pi@RasPi ~ $ ps ax | grep fhem
28839 pts/0    R      0:04 perl fhem.pl fhem.cfg
28843 pts/0    S+     0:00 grep --color=auto fhem


Eine Minute später:

pi@RasPi ~ $ ps ax | grep fhem
28898 pts/0    S+     0:00 grep --color=auto fhem


Spiele jetzt das Backup wieder ein...
live long and prosper
netwalk
_______________________________________________
INTEL NUC7CJYH, Homematic mit 3x HMLGW, JEELINK mit 18x TX29-DTH-IT, DUOFERNSTICK, FB7690 mit FBDECT, NETATMO, Philips HUE, RFXtrx433, 4x Reolink RLC-81MA

CoolTux

Scheint in der Tat weg zu knallen.
Du kannst mal stacktrace in der Config aktivieren. Ist ein Attribut für Global.
Du musst nicht wissen wie es geht! Du musst nur wissen wo es steht, wie es geht.
Support me to buy new test hardware for development: https://www.paypal.com/paypalme/MOldenburg
My FHEM Git: https://git.cooltux.net/FHEM/
Das TuxNet Wiki:
https://www.cooltux.net

netwalk

Aktiviert habe ich stacktrace jetzt.

Wie verfahre ich damit? Update wieder einspielen und auf welche Einträge achten?
Reicht Verbose-Level 3?

Sorry, bin kein Linux/Perl Experte, eher SQL...
live long and prosper
netwalk
_______________________________________________
INTEL NUC7CJYH, Homematic mit 3x HMLGW, JEELINK mit 18x TX29-DTH-IT, DUOFERNSTICK, FB7690 mit FBDECT, NETATMO, Philips HUE, RFXtrx433, 4x Reolink RLC-81MA

CoolTux

Einfach Neustart von FHEM und danach ins Log schauen wenn es wieder weg ist. Default verbose sollte erstmal reichen
Du musst nicht wissen wie es geht! Du musst nur wissen wo es steht, wie es geht.
Support me to buy new test hardware for development: https://www.paypal.com/paypalme/MOldenburg
My FHEM Git: https://git.cooltux.net/FHEM/
Das TuxNet Wiki:
https://www.cooltux.net

netwalk

So, habe es gerade erst geschafft das Update erneut einzuspielen.
Stacktrace ist aktiviert, jedoch ändert sich im Logfile nicht viel:

2016.04.26 07:38:38 0: Server started with 479 defined entities (fhem.pl:11304/2016-04-24 perl:5.014002 os:linux user:fhem pid:16787)
2016.04.26 07:38:38 3: Opening CUBE device 192.168.10.7:62910
2016.04.26 07:38:38 3: CUBE device opened
2016.04.26 07:38:40 3: 20_WLAN01: return error[1] -> connect to to http://192.168.10.150:10000 timed out
2016.04.26 07:38:41 3: 21_WLAN02: return error[1] -> connect to to http://192.168.10.151:10000 timed out
2016.04.26 07:38:41 3: sw.li.eg.Arbeitszimmer.1: return error[1] -> connect to to http://192.168.10.152:10000 timed out
No value specified for 'ForceArray' option in call to XMLin() at ./FHEM/98_EDIPLUG.pm line 223
2016.04.26 07:39:29 1: BlockingInformParent (SYSMON_blockingFinish): Can't connect to localhost:7072: IO::Socket::INET: connect: Verbindungsaufbau abgelehnt


Der Dienst bricht nach knapp einer Minute einfach weg.
live long and prosper
netwalk
_______________________________________________
INTEL NUC7CJYH, Homematic mit 3x HMLGW, JEELINK mit 18x TX29-DTH-IT, DUOFERNSTICK, FB7690 mit FBDECT, NETATMO, Philips HUE, RFXtrx433, 4x Reolink RLC-81MA

netwalk

live long and prosper
netwalk
_______________________________________________
INTEL NUC7CJYH, Homematic mit 3x HMLGW, JEELINK mit 18x TX29-DTH-IT, DUOFERNSTICK, FB7690 mit FBDECT, NETATMO, Philips HUE, RFXtrx433, 4x Reolink RLC-81MA

Wernieman

im Lofile steht:
2016.04.26 07:38:40 3: 20_WLAN01: return error[1] -> connect to to http://192.168.10.150:10000 timed out
2016.04.26 07:38:41 3: 21_WLAN02: return error[1] -> connect to to http://192.168.10.151:10000 timed out
2016.04.26 07:38:41 3: sw.li.eg.Arbeitszimmer.1: return error[1] -> connect to to http://192.168.10.152:10000 timed out


Kannst Du diese 3 Sachen mal "deaktivieren" bzw. löschen?

Sicherheitshalber vorher einfach mal eine kopie der fhem.cfg (per Console)
- Bitte um Input für Output
- When there is a Shell, there is a Way
- Wann war Dein letztes Backup?

Wie man Fragen stellt: https://tty1.net/smart-questions_de.html

netwalk

Dabei handelt es sich um drei EDIMAX WLAN-Steckdosen, die ersten beiden werden z.Zt. nicht eingesetzt, die dritte wird vom Modul EDIPLUG immer direkt nach dem Neustart nicht erkannt, später schon.

Deaktiviert hatte ich sie probeweise auch schon, jedoch ohne Erfolg. FHEM beendet sich trotzdem.
live long and prosper
netwalk
_______________________________________________
INTEL NUC7CJYH, Homematic mit 3x HMLGW, JEELINK mit 18x TX29-DTH-IT, DUOFERNSTICK, FB7690 mit FBDECT, NETATMO, Philips HUE, RFXtrx433, 4x Reolink RLC-81MA

Wernieman

#12
Deaktiviere bitte ein mal sysmon
BlockingInformParent (SYSMON_blockingFinish): Can't connect to localhost:7072: IO::Socket::INET: connect: Verbindungsaufbau abgelehnt

Ansonsten .. Dein Logfile ist nicht Aussagekräftiger? Mal verbose 5 ausprobiert?
- Bitte um Input für Output
- When there is a Shell, there is a Way
- Wann war Dein letztes Backup?

Wie man Fragen stellt: https://tty1.net/smart-questions_de.html

netwalk

Im Logfile steht auch bei Verbose 5 leider nichts Aussagekräftiges und bei jedem Versuch etwas anderes (s. Start-Post).
Stacktrace habe ich auf Anraten von CoolTux auch schon  probiert.

Mir gehen langsam die Ideen und Optionen aus...
live long and prosper
netwalk
_______________________________________________
INTEL NUC7CJYH, Homematic mit 3x HMLGW, JEELINK mit 18x TX29-DTH-IT, DUOFERNSTICK, FB7690 mit FBDECT, NETATMO, Philips HUE, RFXtrx433, 4x Reolink RLC-81MA

Wernieman

Habe meinen Beitrag korrigiert:
"Bitte deaktiviere Sysmon"

"To many Fingers on Keyboard"
- Bitte um Input für Output
- When there is a Shell, there is a Way
- Wann war Dein letztes Backup?

Wie man Fragen stellt: https://tty1.net/smart-questions_de.html