Nie jesteś zalogowany.
Jeśli nie posiadasz konta, zarejestruj je już teraz! Pozwoli Ci ono w pełni korzystać z naszego serwisu. Spamerom dziękujemy!

Ogłoszenie

Prosimy o pomoc dla małej Julki — przekaż 1% podatku na Fundacji Dzieciom zdazyć z Pomocą.
Więcej informacji na dug.net.pl/pomagamy/.

#1  2012-04-04 21:55:42

  dave88 - Użytkownik

dave88
Użytkownik
Zarejestrowany: 2012-01-19

Niespodziewany shutdown serwera

Witam, od jakiegoś czasu zauważyłem że serwer potrafi zaliczyć niespodziewany shutdown przy uptime ~ kilka dni. Wg 'zasady do trzech razy sztuka', postanowiłem to zbadać ;)

Oto log z /var/log/syslog:

Kod:

...
Apr  4 06:25:16 dave rsyslogd: [origin software="rsyslogd" swVersion="5.8.9" x-pid="1122" x-info="http://www.rsyslog.com"] rsyslogd was HUPed
Apr  4 06:39:01 dave /USR/SBIN/CRON[21660]: (root) CMD (  [ -x /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find /var/lib/php5/ -depth -mindepth 1 -maxdepth 1 -type f -ignore_readdir_race -cmin +$(/usr/lib/php5/maxlifetime) ! -execdir fuser -s {} 2>/dev/null \; -delete)
Apr  4 07:09:01 dave /USR/SBIN/CRON[22009]: (root) CMD (  [ -x /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find /var/lib/php5/ -depth -mindepth 1 -maxdepth 1 -type f -ignore_readdir_race -cmin +$(/usr/lib/php5/maxlifetime) ! -execdir fuser -s {} 2>/dev/null \; -delete)
Apr  4 07:17:01 dave /USR/SBIN/CRON[22128]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Apr  4 07:39:01 dave /USR/SBIN/CRON[22357]: (root) CMD (  [ -x /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find /var/lib/php5/ -depth -mindepth 1 -maxdepth 1 -type f -ignore_readdir_race -cmin +$(/usr/lib/php5/maxlifetime) ! -execdir fuser -s {} 2>/dev/null \; -delete)
Apr  4 08:09:01 dave /USR/SBIN/CRON[22701]: (root) CMD (  [ -x /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find /var/lib/php5/ -depth -mindepth 1 -maxdepth 1 -type f -ignore_readdir_race -cmin +$(/usr/lib/php5/maxlifetime) ! -execdir fuser -s {} 2>/dev/null \; -delete)
Apr  4 08:17:01 dave /USR/SBIN/CRON[22830]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Apr  4 08:18:08 dave kernel: [387315.264220] e1000: eth1 NIC Link is Down
Apr  4 08:39:01 dave /USR/SBIN/CRON[23083]: (root) CMD (  [ -x /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find /var/lib/php5/ -depth -mindepth 1 -maxdepth 1 -type f -ignore_readdir_race -cmin +$(/usr/lib/php5/maxlifetime) ! -execdir fuser -s {} 2>/dev/null \; -delete)

Wyłączenie nastapiło ~08:40 czasu polskiego ;)

Urochomiłem serwer ok. 20:00

Kod:

Apr  4 20:01:35 dave kernel: imklog 5.8.9, log source = /proc/kmsg started.
Apr  4 20:01:35 dave rsyslogd: [origin software="rsyslogd" swVersion="5.8.9" x-pid="1206" x-info="http://www.rsyslog.com"] start
Apr  4 20:01:35 dave kernel: [    0.000000] Initializing cgroup subsys cpuset
Apr  4 20:01:35 dave kernel: [    0.000000] Initializing cgroup subsys cpu
Apr  4 20:01:35 dave kernel: [    0.000000] Linux version 3.2.0-1-486 (Debian 3.2.6-1) (ben@decadent.org.uk) (gcc version 4.6.2 (Debian 4.6.2-14) ) #1 Fri Feb 17 05:23:41 UTC 2012
Apr  4 20:01:35 dave kernel: [    0.000000] BIOS-provided physical RAM map:
Apr  4 20:01:35 dave kernel: [    0.000000]  BIOS-e820: 0000000000000000 - 00000000000a0000 (usable)
...

Skąd się wzięły te wpisy w cronie? Jak temu zaradzić?

Offline

 

#2  2012-04-04 22:31:07

  bobycob - Członek z Ramienia

bobycob
Członek z Ramienia
Skąd: Wrocław
Zarejestrowany: 2007-08-15

Re: Niespodziewany shutdown serwera

Dodały się przy instalacji php zapewne do /etc/cron.d/

Czemu się ich czepiasz w logu tym nie ma nic czego można się czepić.
Może ci się serwer zwyczajnie zawiesza?

Offline

 

#3  2012-04-04 23:49:36

  dave88 - Użytkownik

dave88
Użytkownik
Zarejestrowany: 2012-01-19

Re: Niespodziewany shutdown serwera

bobycob napisał(-a):

Czemu się ich czepiasz w logu tym nie ma nic czego można się czepić.

Niepokoi mnie to że za każdym razem przed shutdownem zostaje odpalony ten skrypt dla php...

bobycob napisał(-a):

Może ci się serwer zwyczajnie zawiesza?

Nie wydaje mi się, kilka dni temu testowałem z ciekawości stabilność systemu za pomocą pakietu cpuburn.
Kilka godzin pracy przy full load bez żadnego problemu.

Offline

 

#4  2012-04-05 07:57:06

  bobycob - Członek z Ramienia

bobycob
Członek z Ramienia
Skąd: Wrocław
Zarejestrowany: 2007-08-15

Re: Niespodziewany shutdown serwera

Mi się jednak nic nie wyłącza w wyniku uruchomienia standardowych skryptów cron.
Więc przyczyny należy szukać gdzie indziej. Serwer jest podtrzymywany i nie ma zaników zasilania?

Offline

 

#5  2013-02-20 21:51:14

  dave88 - Użytkownik

dave88
Użytkownik
Zarejestrowany: 2012-01-19

Re: Niespodziewany shutdown serwera

Witam,
Po długiej przerwie problem z dostępnością serwera powrócił... Wygląda to tak że po kilku dniach, najczęściej w nocy coś się sypie i tracę kontakt z serwerem (chociaż widać że pracuje cały czas). Przestaje działaś sieć (mam na nim NAT + DHCP + wiele innych rzeczy) i nie mogę połączyć się przez SSH... Zauważyłem że problem pojawił się po instalacji Cacti oraz demona SNMP i dodaniu wpisu do Crona... Przejrzałem logi ale nie widzę niczego niepokojącego. Wersja systemu jak widać poniżej: Debian 2.6.32-46 stable. Poniżej zamieszczam fragmenty logów z dzisiaj nocy, kiedy nastąpił zwis:
cat /var/log/syslog

Kod:

Feb 20 02:15:03 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:53968->[127.0.0.1]
Feb 20 02:15:03 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:39041->[127.0.0.1]
Feb 20 02:15:03 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:42800->[127.0.0.1]
Feb 20 02:17:01 debian /USR/SBIN/CRON[25747]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Feb 20 02:20:01 debian /USR/SBIN/CRON[25808]: (cacti) CMD (/usr/bin/php /var/www/cacti/poller.php > /dev/null 2>&1)
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:60334->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:60110->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:57616->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:35254->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:53335->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:52513->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:47454->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:38762->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:36838->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:45952->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:55277->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:55619->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:35929->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:60859->[127.0.0.1]
Feb 20 18:12:56 debian kernel: imklog 4.6.4, log source = /proc/kmsg started.
Feb 20 18:12:56 debian rsyslogd: [origin software="rsyslogd" swVersion="4.6.4" x-pid="1394" x-info="http://www.rsyslog.com"] (re)start
Feb 20 18:12:56 debian kernel: [    0.000000] Initializing cgroup subsys cpuset
Feb 20 18:12:56 debian kernel: [    0.000000] Initializing cgroup subsys cpu
Feb 20 18:12:56 debian kernel: [    0.000000] Linux version 2.6.32-5-686 (Debian 2.6.32-46) (dannf@debian.org) (gcc version 4.3.5 (Debian 4.3.5-4) ) #1 SMP Sun Sep 23 09:49:36 UTC

cat  /var/log/auth.log

Kod:

Feb 20 02:15:03 debian CRON[25712]: pam_unix(cron:session): session closed for user cacti
Feb 20 02:17:01 debian CRON[25745]: pam_unix(cron:session): session opened for user root by (uid=0)
Feb 20 02:17:01 debian CRON[25745]: pam_unix(cron:session): session closed for user root
Feb 20 02:20:01 debian CRON[25806]: pam_unix(cron:session): session opened for user cacti by (uid=0)
Feb 20 02:20:03 debian CRON[25806]: pam_unix(cron:session): session closed for user cacti
Feb 20 18:13:07 debian sshd[1865]: Server listening on 0.0.0.0 port xxxx.
Feb 20 18:13:07 debian sshd[1865]: Server listening on :: port xxxx.

cat /var/log/daemon.log

Kod:

Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:52513->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:47454->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:38762->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:36838->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:45952->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:55277->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:55619->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:35929->[127.0.0.1]
Feb 20 02:20:02 debian snmpd[1879]: Connection from UDP: [127.0.0.1]:60859->[127.0.0.1]
Feb 20 18:12:56 debian vnstatd[1423]: vnStat daemon 1.10 started.
Feb 20 18:12:56 debian vnstatd[1423]: Monitoring: eth1 eth0 
Feb 20 18:12:56 debian acpid: starting up with netlink and the input layer
Feb 20 18:12:56 debian acpid: 1 rule loaded

I tutaj niestety pojawia się skan, ale serwer działał po tym jeszcze z 2 godziny...
cat /var/log/apache2/error.log

Kod:

[Tue Feb 19 22:47:07 2013] [error] [client 217.xxx.xxx.xxx] File does not exist: /var/www/favicon.ico, referer: http://xxxxxxxxxxxxx/
[Wed Feb 20 00:41:22 2013] [error] [client 89.xxx.xxx.xxx] File does not exist: /var/www/w00tw00t.at.blackhats.romanian.anti-sec:)
[Wed Feb 20 00:41:22 2013] [error] [client  89.xxx.xxx.xxx] File does not exist: /var/www/phpMyAdmin
[Wed Feb 20 00:41:23 2013] [error] [client  89.xxx.xxx.xxx] File does not exist: /usr/share/phpmyadmin/scripts
[Wed Feb 20 00:41:23 2013] [error] [client  89.xxx.xxx.xxx] File does not exist: /var/www/pma
[Wed Feb 20 00:41:26 2013] [error] [client  89.xxx.xxx.xxx] File does not exist: /var/www/MyAdmin
PHP Warning:  PHP Startup: Unable to load dynamic library '/usr/lib/php5/20090626+lfs/msql.so' - /usr/lib/php5/20090626+lfs/msql.so: cannot open shared object file: No such file or directory in Unknown on line 0
[Wed Feb 20 18:12:59 2013] [notice] Apache/2.2.16 (Debian) PHP/5.3.3-7+squeeze14 with Suhosin-Patch mod_scgi/1.13 configured -- resuming normal operations

Jakieś sugestie?

Offline

 

Stopka forum

Powered by PunBB
© Copyright 2002–2005 Rickard Andersson
Możesz wyłączyć AdBlock — tu nie ma reklam ;-)