Shared-Memory Segment loeschen..

ksmx · 20. August 2012

Hi,

langsam wird es gruselig. Eben ist mir eine Lab-Version bei einem Update auf den Server gerutscht und prompt fangen leider die Probleme an. Das Update beendet mit einem "LiveConfig failed". Der Grund ist eine etwas wenig aussagekraeftige Fehlermeldung:

Code

Server already running? Can't create shared memory segment: File exists

Im Handbuch habe ich dann den entscheidenen Hinweis gefunden. Das shmem-Segment soll man angeblich per

Code

ipcrm -s <shmid>

loeschen koennen. Den Parameter -s gibt es zwar... fuehrt aber in meinem Fall (Debian Squeeze) nicht zum Ziel. Vielmehr ist

Code

ipcrm -m <shmid>

der korrekte Parameter. Ein anschließender Start erfolgte glückerweise reibungslos.

Gruss ksmx

kk · 20. August 2012

Danke für den Hinweis, ist ein Doku-Fehler - es ist wirklich "-m" zu verwenden.

Wir haben das Problem nach dem Neustart auf einigen Testsystemen auch schon beobachtet; letztendlich scheint es eine Timing-Sache zu sein: der "start-stop-daemon" kehrt zu schnell zurück bzw. beendet den laufenden LiveConfig-Prozess nicht wirklich; der anschließende Neustart schlägt dann natürlich fehl. Ich weiß nicht ob Sie das nun noch prüfen können, aber stand in der Spalte "nattch" (number of attached processes) wirklich "0"? Wenn LC korrekt beendet wurde (also nicht mit einem SEGFAULT abgestürzt ist) wird der Shared Memory nämlich immer freigegeben. Eventuell finden Sie auch noch Hinweise in der LiveConfig-Logdatei.

Wir werden das Init-Script morgen noch mal entsprechend überarbeiten, um an dieser Stelle etwas "robuster" zu sein.

Viele Grüße

-Klaus Keppler

ksmx · 21. August 2012

Wie der nattch-Wert zu diesem Zeitpunkt war, kann ich leider nicht mehr beschwoeren. Hier ein Logauszug:

Code

[2012/08/20 22:48:10.130620] [24693|24693] Received SIGTERM, immediately terminating child processes
[2012/08/20 22:48:10.130859] [24694|24694] Server child terminating... (shutdown=0, exitcode=0)
[2012/08/20 22:48:10.130919] [24694|24694] LiveConfig terminated.
[2012/08/20 22:48:13.380229] [24694|24694] Closing log file
[2012/08/20 22:48:13.383627] [24693|24693] Server child process 24694 terminated; return code: 0
[2012/08/20 22:48:13.383657] [24693|24693] Resource usage statistics:
[2012/08/20 22:48:13.383669] [24693|24693]        Uptime: 292720 sec.
[2012/08/20 22:48:13.383680] [24693|24693]     User time: 11.0176
[2012/08/20 22:48:13.383691] [24693|24693]   System time: 13.0672
[2012/08/20 22:48:54.535837] [12277|12277] LiveConfig 1.5.1-1754 starting...
[2012/08/20 22:48:54.535931] [12277|12277] Database driver loaded: SQLite (3.7.13)
[2012/08/20 22:48:54.539510] [12277|12277] Upgrading database schema (r1695 -> r1705)
[2012/08/20 22:48:54.568774] [12277|12277] Upgrading database schema (r1705 -> r1715)
[2012/08/20 22:48:54.572326] [12277|12277] Upgrading database schema (r1715 -> r1719)
[2012/08/20 22:48:54.575570] [12277|12277] Upgrading database schema (r1719 -> r1731)
[2012/08/20 22:48:54.577202] [12277|12277] Upgrading database schema (r1731 -> r1737)
[2012/08/20 22:48:54.584460] [12277|12277] License is valid.
[2012/08/20 22:48:54.585558] [12277|12277] Server already running? Can't create shared memory segment: File exists
[2012/08/20 22:48:54.585605] [12277|12277] Closing log file
[2012/08/20 22:49:04.827643] [12467|12467] LiveConfig 1.5.1-1754 starting...
[2012/08/20 22:49:04.827822] [12467|12467] Database driver loaded: SQLite (3.7.13)
[2012/08/20 22:49:04.835669] [12467|12467] License is valid.
[2012/08/20 22:49:04.836333] [12467|12467] Server already running? Can't create shared memory segment: File exists

Alles anzeigen

Mir ist noch etwas eingefallen/aufgefallen: Nachdem der Update-Prozess die neue Liveconfig-Instanz nicht ordentlich starten konnte habe ich mir die Prozessliste angesehen und den Prozess 24696 gefunden (scheinbar auch ein weiterer Thread). Diesen habe ich per kill terminiert. Vielleicht ist es ein Indiz dafuer, dass liveconfig nicht unter jedem Umstand sauber/komplett beendet.

Gruss ksmx

kk · 21. August 2012

Diesem Log-Auszug und der Beschreibung nach zu urteilen lief noch ein Child-Prozess* von LiveConfig der nicht ordentlich beendet war - das erklärt, warum der Shared Memory noch vorhanden war.
Der Grund für den hängenden Child-Prozess wiederum war vermutlich irgendwann mal ein Fehler/Abbruch in einem der Lua-Scripte, was zu einem Deadlock in einem der Lua-Threads führte - durch eine Änderung sollte dieses nun auch nicht mehr auftreten (wenn ein Lua-Script abbricht, werden künftig alle Ressourcen automatisch wieder freigegeben)

Falls noch mal irgendwas "Merkwürdiges" passiert geben Sie bitte Bescheid - LiveConfig soll eigentlich möglichst robust sein.

Viele Grüße

-Klaus Keppler

*) LiveConfig startet drei Prozesse: einen Watchdog, einen Server-Prozess für die GUI (als User "liveconfig") und einen Client-Prozess für die Konfigurationsarbeiten. Der LiveConfig-Client (lcclient) enthält praktisch nur den Watchdog und den Client-Prozess.

Jetzt mitmachen!