Hi,
ich habe vor ein paar Stunden auf die letzte Preview aktualisiert. Gerade eben musste ich feststellen, dass der "liveconfig"-Prozess Amok laeuft.
Tasks: 197 total, 2 running, 195 sleeping, 0 stopped, 0 zombie
Cpu(s): 13.2%us, 0.4%sy, 0.0%ni, 86.3%id, 0.0%wa, 0.0%hi, 0.0%si, 0.1%st
Mem: 27254620k total, 23360592k used, 3894028k free, 229020k buffers
Swap: 33554424k total, 458656k used, 33095768k free, 9196236k cached
PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND
12502 liveconf 20 0 161m 19m 2492 R 99 0.1 111:54.78 liveconfig
[...]
Der Restart wurde mit einem "failed" quittiert. Der Inhalt des Logs sieht aktuell so aus:
[2012/10/25 22:02:33.007736] [12501|12501] Received SIGTERM, immediately terminating child processes
[2012/10/25 22:03:03.061580] [29564|29564] LiveConfig 1.6.0-1979 starting...
[2012/10/25 22:03:03.061673] [29564|29564] Database driver loaded: SQLite (3.7.13)
[2012/10/25 22:03:03.101702] [29564|29564] License is valid.
[2012/10/25 22:03:03.102000] [29564|29564] Can't open any socket for address (null), port 8443
[2012/10/25 22:03:03.102046] [29564|29564] Closing log file
Scheinbar lauscht dort noch etwas gestorbenes am Port 8443.
$ netstat -putln | grep 8443
tcp 0 0 0.0.0.0:8443 0.0.0.0:* LISTEN 12502/liveconfig
Per SIGTERM laesst sich der Prozess nicht verjagen - 100% CPU konsumiert er weiterhin. Ich habe den Prozess per SIGKILL beendet. Das uebrig bleibende SHM-Segment sieht so aus:
$ ipcs
------ Gemeinsamer Speicher: Segmente --------
Schlüssel shmid Besitzer Rechte Bytes nattch Status
0x1702809c 1998848 root 640 296 0
0x0102029a 2031617 root 600 1200712 7
0x63637069 65538 root 666 65536 0
------ Semaphorenfelder --------
Schlüssel SemID Besitzer Rechte nsems
0x00000000 5242880 www-data 600 1
0x00000000 5275649 www-data 600 1
0x00000000 5308418 www-data 600 1
0x63637069 98307 root 666 3
------ Nachrichtenwarteschlangen --------
Schlüssel msqid Besitzer Rechte Benutzt-Bytes Nachrichten
Alles anzeigen
Hui, nach einem Neustart standen aussagekraeftige Zeilen im Log:
[2012/10/25 22:12:14.498061] [1033|1033] Connected to LiveConfig 1.6.0
[2012/10/25 22:12:14.507128] [1033|1035] Can't run /usr/lib/liveconfig/lua/liveconfig.lua: error loading module 'postfix' from file '/usr/lib/liveconfig/lua/postfix.lua':
/usr/lib/liveconfig/lua/postfix.lua:528: 'then' expected near 'fh'
[2012/10/25 22:12:14.507382] [1033|1034] Can't run /usr/lib/liveconfig/lua/liveconfig.lua: error loading module 'postfix' from file '/usr/lib/liveconfig/lua/postfix.lua':
/usr/lib/liveconfig/lua/postfix.lua:528: 'then' expected near 'fh'
[2012/10/25 22:12:14.507585] [1033|1036] Can't run /usr/lib/liveconfig/lua/liveconfig.lua: error loading module 'postfix' from file '/usr/lib/liveconfig/lua/postfix.lua':
/usr/lib/liveconfig/lua/postfix.lua:528: 'then' expected near 'fh'
[2012/10/25 22:12:14.510049] [1012|1012] Client child process 1033 terminated; uncaught signal: 11 (Segmentation fault)
[2012/10/25 22:12:14.510104] [1012|1012] Resource usage statistics:
[2012/10/25 22:12:14.510116] [1012|1012] Uptime: 0 sec.
[2012/10/25 22:12:14.510127] [1012|1012] User time: 0.0036
[2012/10/25 22:12:14.510138] [1012|1012] System time: 0.0020
[2012/10/25 22:12:14.510178] [1012|1012] Backtrace:
[2012/10/25 22:12:14.510190] [1012|1012] [ 0] 0x45a1c5
[2012/10/25 22:12:14.510201] [1012|1012] [ 1] 0x44a6bc
[2012/10/25 22:12:14.510211] [1012|1012] [ 2] 0xc143aff0
[2012/10/25 22:12:14.510222] [1012|1012] [ 3] 0x675d0a
[2012/10/25 22:12:14.510232] [1012|1012] [ 4] 0x66d317
[2012/10/25 22:12:14.510241] [1012|1012] [ 5] 0x4434c8
[2012/10/25 22:12:14.510251] [1012|1012] [ 6] 0x45364d
[2012/10/25 22:12:14.510261] [1012|1012] [ 7] 0x4536bb
[2012/10/25 22:12:14.510271] [1012|1012] [ 8] 0x696ea9
[2012/10/25 22:12:14.510281] [1012|1012] [ 9] 0xc14328ca
[2012/10/25 22:12:14.510291] [1012|1012] [10] 0xc07e9b6d
[2012/10/25 22:12:14.511943] [1037|1037] Client child started...
[2012/10/25 22:12:14.518517] [1037|1037] Connected to LiveConfig 1.6.0
[2012/10/25 22:12:14.528385] [1037|1039] Can't run /usr/lib/liveconfig/lua/liveconfig.lua: error loading module 'postfix' from file '/usr/lib/liveconfig/lua/postfix.lua':
/usr/lib/liveconfig/lua/postfix.lua:528: 'then' expected near 'fh'
[2012/10/25 22:12:14.530244] [1012|1012] Client child process 1037 terminated; uncaught signal: 11 (Segmentation fault)
[2012/10/25 22:12:14.530307] [1012|1012] Resource usage statistics:
[2012/10/25 22:12:14.530325] [1012|1012] Uptime: 0 sec.
[2012/10/25 22:12:14.530338] [1012|1012] User time: 0.0032
[2012/10/25 22:12:14.530349] [1012|1012] System time: 0.0020
[2012/10/25 22:12:14.530360] [1012|1012] Backtrace:
[2012/10/25 22:12:14.530370] [1012|1012] [ 0] 0x45a1c5
[2012/10/25 22:12:14.530381] [1012|1012] [ 1] 0x44a6bc
[2012/10/25 22:12:14.530392] [1012|1012] [ 2] 0xc143aff0
[2012/10/25 22:12:14.530402] [1012|1012] [ 3] 0x675d0a
[2012/10/25 22:12:14.530412] [1012|1012] [ 4] 0x66d317
[2012/10/25 22:12:14.530422] [1012|1012] [ 5] 0x4434c8
[2012/10/25 22:12:14.530432] [1012|1012] [ 6] 0x45364d
[2012/10/25 22:12:14.530442] [1012|1012] [ 7] 0x4536bb
[2012/10/25 22:12:14.530452] [1012|1012] [ 8] 0x696ea9
[2012/10/25 22:12:14.530462] [1012|1012] [ 9] 0xc14328ca
[2012/10/25 22:12:14.530472] [1012|1012] [10] 0xc07e9b6d
[2012/10/25 22:12:14.532580] [1041|1041] Client child started...
[2012/10/25 22:12:14.538883] [1041|1041] Connected to LiveConfig 1.6.0
[2012/10/25 22:12:14.542588] [1041|1043] Can't run /usr/lib/liveconfig/lua/liveconfig.lua: error loading module 'postfix' from file '/usr/lib/liveconfig/lua/postfix.lua':
/usr/lib/liveconfig/lua/postfix.lua:528: 'then' expected near 'fh'
[2012/10/25 22:12:14.543884] [1012|1012] Client process spawned more than 5x within the last 10 seconds - aborting...
[2012/10/25 22:12:14.543945] [1012|1012] Client child process 1041 terminated; uncaught signal: 11 (Segmentation fault)
[2012/10/25 22:12:14.543959] [1012|1012] Resource usage statistics:
[2012/10/25 22:12:14.543970] [1012|1012] Uptime: 0 sec.
[2012/10/25 22:12:14.543981] [1012|1012] User time: 0.0020
[2012/10/25 22:12:14.543992] [1012|1012] System time: 0.0008
[2012/10/25 22:12:14.544003] [1012|1012] Backtrace:
[2012/10/25 22:12:14.544013] [1012|1012] [ 0] 0x45a1c5
[2012/10/25 22:12:14.544024] [1012|1012] [ 1] 0x44a6bc
[2012/10/25 22:12:14.544034] [1012|1012] [ 2] 0xc143aff0
[2012/10/25 22:12:14.544045] [1012|1012] [ 3] 0x675d0a
[2012/10/25 22:12:14.544055] [1012|1012] [ 4] 0x66d317
[2012/10/25 22:12:14.544065] [1012|1012] [ 5] 0x4434c8
[2012/10/25 22:12:14.544075] [1012|1012] [ 6] 0x45364d
[2012/10/25 22:12:14.544085] [1012|1012] [ 7] 0x4536bb
[2012/10/25 22:12:14.544095] [1012|1012] [ 8] 0x696ea9
[2012/10/25 22:12:14.544105] [1012|1012] [ 9] 0xc14328ca
[2012/10/25 22:12:14.544115] [1012|1012] [10] 0xc07e9b6d
[2012/10/25 22:12:22.030193] [1013|1013] Connection closed by peer
[2012/10/25 22:12:22.093301] [1013|1013] Connection closed by peer
[2012/10/25 22:12:22.156655] [1013|1013] Connection closed by peer
[2012/10/25 22:12:22.216220] [1013|1013] Connection closed by peer
[2012/10/25 22:12:22.272080] [1013|1013] Connection closed by peer
[2012/10/25 22:12:22.327599] [1013|1013] Connection closed by peer
[2012/10/25 22:12:22.388933] [1013|1013] Connection closed by peer
Alles anzeigen