shutdown restart funktioniert nicht

[bgewehr]

Ist eine FritzBox 7390!

auf ps | grep fhem per Telnet auf Port 23 kommt

Code Auswählen Erweitern

Server> ps | grep fhem
Server>  1655 root      1244 S    {startfhem} /bin/sh /var/InternerSpeicher/fhem/start
Server>  1791 root     18284 S    perl fhem.pl fhem.cfg

nun shutdown restart führt zu ... meterweise logs nach dem Schema

Code Auswählen Erweitern


Server> # system-load 2 curr: perl runnable: 2
Server> system-load 7 curr: perl runnable: 2
Server> SysRq : Changing Loglevel
Server> Loglevel set to 4
Server> [AVMDBG_OVR] -> skip header  should not happen!
Server> http header does not start at beginning of packet -> skip header  should not happen!
Server> http header does not start at beginning of packet -> skip header  should not happen!
Server> system-load 2 curr: ctlmgr_ctl runnable: 3
Server> system-load 2 curr: perl runnable: 1
Server> system-load 3 curr: ctlmgr_ctl runnable: 1
Server> avm_pa: telephony active (reduce)
Server> avm_pa: telephony inactive
Server> system-load 2 curr: ctlmgr_ctl runnable: 2
Server> system-load 2 curr: ctlmgr_ctl runnable: 2
Server> avm_pa: telephony active (reduce)
Server> system-load 2 curr: ctlmgr_ctl runnable: 2
Server> avm_pa: load reduce 0 => 1, rate 1750 (pps 33)
Server> avm_pa: load reduce 1 => 0, rate 1750 (pps 33 ov_pps 0)
Server> avm_pa: telephony inactive
Server> TFFS: F�llstand > 75 ... trigger Cleanup
Server> system-load 2 curr: ctlmgr_ctl runnable: 3
Server> [AHA] SIGHUP received
Server>
Server>
Server> [AHA] SIGHUP received
Server>
Server>
Server> [aha: avm_home_upper_connection_thread] Error: message received (ret=-1, errno 4, 'Interrupted system call')
Server> [AHA] SIGHUP received
Server>
Server>
Server> nactive
Server> avm_pa: telephony active (reduce)
Server> avm_pa: telephony inactive
Server> avm_pa: telephony active (reduce)
Server> avm_pa: telephony inactive
Server> avm_pa: telephony active (reduce)
Server> system-load 2 curr: ctlmgr_ctl runnable: 3
Server> system-load 1 curr: perl runnable: 2
Server> avm_pa: load reduce 0 => 1, rate 1989 (pps 39)
Server> avm_pa: load reduce 1 => 0, rate 1989 (pps 39 ov_pps 0)
Server> system-load 2 curr: ctlmgr runnable: 2
Server> system-load 2 curr: ctlmgr_ctl runnable: 3
Server> system-load 2 curr: ctlmgr_ctl runnable: 3
Server> avm_pa: load reduce 0 => 1, rate 1989 (pps 41)
Server> avm_pa: load reduce 1 => 0, rate 1989 (pps 55 ov_pps 0)
Server> system-load 3 curr: perl runnable: 3
Server> avm_pa: telephony inactive
Server> system-load 2 curr: ctlmgr_ctl runnable: 1
Server> system-load 2 curr: perl runnable: 1
Server> system-load 1 curr: ctlmgr_ctl runnable: 1
Server> system-load 1 curr: ctlmgr_ctl runnable: 2
Server> system-load 1 curr: ctlmgr_ctl runnable: 3
Server> system-load 2 curr: ctlmgr_ctl runnable: 3
Server> system-load 2 curr: ctlmgr_ctl runnable: 3
Server> system-load 3 curr: perl runnable: 1
Server> system-load 5 curr: perl runnable: 1
Server> system-load 6 curr: ctlmgr_ctl runnable: 3
Server> system-load 1 curr: perl runnable: 2
Server> system-load 6 curr: perl runnable: 3
Server> system-load 2 curr: perl runnable: 1
Server> system-load 6 curr: ctlmgr_ctl runnable: 3
Server> system-load 2 curr: ctlmgr_ctl runnable: 3
Server> system-load 2 curr: perl runnable: 1
Server> system-load 6 curr: perl runnable: 1
Server> system-load 2 curr: ctlmgr_ctl runnable: 1
Server> system-load 3 curr: perl runnable: 2
Server> [vap-0: 16:00:50:f2:01:01] discard assoc_req frame wrong bssid
Server> [vap-0: 16:00:50:f2:01:01] discard assoc_req frame wrong bssid
Server> [vap-0: 16:00:50:f2:01:01] discard assoc_req frame wrong bssid
Server> [vap-0: 16:00:50:f2:01:01] discard assoc_req frame wrong bssid
Server> system-load 2 curr: ctlmgr runnable: 2
Server> system-load 2 curr: perl runnable: 2
Server> system-load 3 curr: ctlmgr_ctl runnable: 3
Server> system-load 2 curr: ctlmgr runnable: 2
Server> kdsld: flushing internet sessions
Server> kdsld: flushing internet sessions
Server> kdsld: flushing internet sessions
Server> system-load 3 curr: ctlmgr runnable: 4
Server> vdsl_pa_dev_transmit(vdsl) 1
Server> vdsl_pa_dev_transmit(vdsl) 1
Server> vdsl_pa_dev_transmit(vdsl) 1
Server> vdsl_pa_dev_transmit(vdsl) 1
Server> vdsl_pa_dev_transmit(vdsl) 1
Server> vdsl_pa_dev_transmit(vdsl) 1
Server> vdsl_pa_dev_transmit(vdsl) 1
Server> vdsl_pa_dev_transmit(vdsl) 1
Server> vdsl_pa_dev_transmit(vdsl) 1
Server> vdsl_pa_dev_transmit(vdsl) 1system-load 1 curr: perl runnable: 1
Server> system-load 2 curr: ctlmgr_ctl runnable: 3
Server> system-load 2 curr: ctlmgr_ctl runnable: 3
Server> system-load 2 curr: ctlmgr_ctl runnable: 1
Server> system-load 1 curr: ctlmgr_ctl runnable: 3
Server> system-load 1 curr: perl runnable: 3
Server> system-load 2 curr: ctlmgr_ctl runnable: 3
Server> system-load 2 curr: ctlmgr_ctl runnable: 5
Server> system-load 2 curr: ctlmgr_ctl runnable: 7
Server> system-load 2 curr: ctlmgr_ctl runnable: 1
Server> system-load 2 curr: perl runnable: 1
Server> system-load 2 curr: perl runnable: 1
Server> system-load 2 curr: ctlmgr_ctl runnable: 3
Server> system-load 2 curr: perl runnable: 1
Server> system-load 2 curr: ctlmgr_ctl runnable: 1
Server> system-load 2 curr: ctlmgr runnable: 4
Server> system-load 2 curr: ctlmgr_ctl runnable: 3
Server> system-load 2 curr: ctlmgr_ctl runnable: 3
Server> system-load 2 curr: ctlmgr_ctl runnable: 2
Server> system-load 2 curr: ctlmgr_ctl runnable: 3
Server> system-load 2 curr: ar7cfgctl runnable: 3
Server> system-load 1 curr: ar7cfgctl runnable: 2
Server> do_page_fault() #2: sending SIGSEGV to smbd for invalid read access from
Server> 00000000 (epc == 00000000, ra == 004507BD)
Server> system-load 2 curr: ctlmgr_ctl runnable: 3
Server> __ratelimit: 18 callbacks suppressed

Dann Stille!

dann wieder ps | grep fhem:

Code Auswählen Erweitern

Server> ps | grep fhem
Server>  3451 root      1236 S    grep fhem

Auf dem Web-Interface kein response.

Muss die Box neu starten, damit wieder alles läuft...

[Puschel74]

Hallo,

auch wenn ich jetzt, ahnungslos, über das Ziel hinaus schiesse ...

Hast du schonmal das Recover-File für die 7390 vom AVM-ftp-Server gezogen und die Box mal in den
"Urzustand" zurück gesetzt?

ACHTUNG!! Anleitung genauestens lesen und daran halten!!!

Damit solltest du "interne" "Verbiegungen" in den Bits und Bytes wieder gerade rücken können.
Nachdem der Beitrag schon länger so geht wäre ein Recover die schnellere Variante (ca. 5 Minuten) ;-)

Wenns hilft ist gut wenn nicht, naja - ists auch nicht schlimm.

Grüße

[justme1968]

mir ist es zwei mal auf einer synology diskstation passiert. durch zufall habe ich bei einem mal in der consolen ausgabe ein fehler beim öffnen des telnet posts gesehen. ich tippe zumindest bei mir auf ein timing problem das beim restart der port noch belegt ist und dann das restart hängt.

[locodriver]

Ich habe heute auch mal mit Putty die Veränderung der laufenden Prozesse protokolliert, habe aber k.A., wie das zu interpretieren ist:

Zuerst laufendes fhem: #  4309 boxusr80 14920 S    perl fhem.pl fhem.cfg
dann shutdown restart: #  4800 boxusr80 10848 S    perl fhem.pl fhem.cfg
Neutart mit Putty:     #  4800 boxusr80 10856 S    perl fhem.pl fhem.cfg
nach neuem Aufruf von fhem im Browser:
 # ps | grep fhem
 4800 boxusr80 13296 S    perl fhem.pl fhem.cfg
 4821 root      1416 S    grep fhem

Den Tipp zum Recovern der FB habe ich noch nicht getestet.

Uwe

[ChrisW]

Hab im Windows Bereich auch schon mal deswegen was aufgemacht. Da Funktioniert es bis heute leider auch nicht :(

[justme1968]

gerade eben ist ein neu start schief gegangen. das hier sind die zeilen aus dem log:

Code Auswählen Erweitern

2013.01.21 23:57:55 0: Server shutdown
2013.01.21 23:57:57 1: Including /usr/local/FHEM/etc/fhem.cfg
2013.01.21 23:57:57 1: telnetPort: Can't open server port at 7072: Address already in use. Exiting.

gruss
  andre

[bgewehr]

Das klingt ja so, als würde es schin helfen, noch ein bisschen zu warten... kann das wer einbauen?

[locodriver]

Ich will das Thema heute noch mal aufnehmen:

Das Problem besteht nach wie vor, ich habe gerade ein update gefahren und der Neustart klappte wieder nicht. Ich kopiere mal die Putty-Ausgaben:

Code Auswählen Erweitern

# Use of uninitialized value in string eq at fhem.pl line 2698, <$fh> line 21.
Use of uninitialized value $str in join or string at fhem.pl line 2701, <$fh> line 21.
Use of uninitialized value in string eq at fhem.pl line 2698, <$fh> line 136.
Use of uninitialized value $str in join or string at fhem.pl line 2701, <$fh> line 136.
Use of uninitialized value in string eq at fhem.pl line 2698, <$fh> line 221.
Use of uninitialized value $str in join or string at fhem.pl line 2701, <$fh> line 221.
Use of uninitialized value in string eq at fhem.pl line 2698, <$fh> line 232.
Use of uninitialized value $str in join or string at fhem.pl line 2701, <$fh> line 232.
Use of uninitialized value in hash element at ./FHEM/10_CUL_HM.pm line 3033.
Use of uninitialized value $lstAr in split at ./FHEM/10_CUL_HM.pm line 3034.
# ls
CHANGED         dfu-programmer  fhem.cfg.org    lib             startfhem
FHEM            docs            fhem.pl         log             unused
backup          fhem.cfg        fhem.pl.org     perl            www
# ./startfhem
chown: unknown user boxusr80
chown: unknown user boxusr80
#


Vielleicht hilft das bei der Suche nach dem Fehler?

Danke Uwe.

[justme1968]

zumindest auf meiner syno passiert es fast regelmäßig und immer mit der besagten telnet port meldung.

gruss
  andre

[justme1968]

gerade eben bemerkt das es mit einem rereadcfg bei mir immer reproduzierbar ist. die 'Address already in use' meldung kommt und fhem beendet sich.

das telnet_Undef wird auch nicht aufgerufen und das scheint die einzige stelle zu sein von wo aus TcpServer_Close aufgerufen wird.

gruss
  nadre

[bgewehr]

Seit meinem letzten fhem Update läuft shutdown auch mit Restart!

[locodriver]

Das ist ja mal 'ne gute Nachricht, werde ich am WE auch ausprobieren. Das Neustarten mit Putty nervt doch ganz schön...
Hast du noch irgendwas in den Einstellungen geändert?

Danke Uwe

[locodriver]

... und leider hat sich bei mir nichts geändert - also weiter Putty nutzen. :-(

Schönes WE

Uwe