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/.

Strony: 1

 

#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 [i]/var/log/syslog[/i]:

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

[quote=bobycob]Czemu się ich czepiasz w logu tym nie ma nic czego można się czepić.[/quote]
Niepokoi mnie to że za każdym razem przed shutdownem zostaje odpalony ten skrypt dla php...

[quote=bobycob]Może ci się serwer zwyczajnie zawiesza?[/quote]
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

 

 

Strony: 1

Stopka forum

Powered by PunBB
© Copyright 2002–2005 Rickard Andersson
Nas ludzie lubią po prostu, a nie klikając w przyciski ;-)

[ Generated in 0.009 seconds, 11 queries executed ]

Informacje debugowania

Time (s) Query
0.00011 SET CHARSET latin2
0.00007 SET NAMES latin2
0.00096 SELECT u.*, g.*, o.logged FROM punbb_users AS u INNER JOIN punbb_groups AS g ON u.group_id=g.g_id LEFT JOIN punbb_online AS o ON o.ident='18.221.167.11' WHERE u.id=1
0.00087 REPLACE INTO punbb_online (user_id, ident, logged) VALUES(1, '18.221.167.11', 1732396949)
0.00044 SELECT * FROM punbb_online WHERE logged<1732396649
0.00048 SELECT topic_id FROM punbb_posts WHERE id=225143
0.00130 SELECT id FROM punbb_posts WHERE topic_id=21019 ORDER BY posted
0.00084 SELECT t.subject, t.closed, t.num_replies, t.sticky, f.id AS forum_id, f.forum_name, f.moderators, fp.post_replies, 0 FROM punbb_topics AS t INNER JOIN punbb_forums AS f ON f.id=t.forum_id LEFT JOIN punbb_forum_perms AS fp ON (fp.forum_id=f.id AND fp.group_id=3) WHERE (fp.read_forum IS NULL OR fp.read_forum=1) AND t.id=21019 AND t.moved_to IS NULL
0.00004 SELECT search_for, replace_with FROM punbb_censoring
0.00080 SELECT u.email, u.title, u.url, u.location, u.use_avatar, u.signature, u.email_setting, u.num_posts, u.registered, u.admin_note, p.id, p.poster AS username, p.poster_id, p.poster_ip, p.poster_email, p.message, p.hide_smilies, p.posted, p.edited, p.edited_by, g.g_id, g.g_user_title, o.user_id AS is_online FROM punbb_posts AS p INNER JOIN punbb_users AS u ON u.id=p.poster_id INNER JOIN punbb_groups AS g ON g.g_id=u.group_id LEFT JOIN punbb_online AS o ON (o.user_id=u.id AND o.user_id!=1 AND o.idle=0) WHERE p.topic_id=21019 ORDER BY p.id LIMIT 0,25
0.00118 UPDATE punbb_topics SET num_views=num_views+1 WHERE id=21019
Total query time: 0.00709 s