httpd-users mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Fabio F.Gervasi" <fabio.gervas...@gmail.com>
Subject Re: [users@httpd] Random Internal Server Error 500 after apache and system update
Date Sun, 02 Oct 2016 08:37:45 GMT
Hi Daniel,

the *output* file of the first command, *strace -o /tmp/outputfile -s 5000
httpd -X*:

execve("/usr/sbin/httpd", ["httpd", "-X"], [/* 25 vars */]) = 0
brk(NULL)                               = 0x55882e0b8000
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
0x7f8cda68d000
access("/etc/ld.so.preload", R_OK)      = -1 ENOENT (No such file or
directory)
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=54641, ...}) = 0
mmap(NULL, 54641, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f8cda67f000
close(3)                                = 0
open("/lib64/libpcre.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\26\0\0\0\0\0\0@
\0\0\0\0\0\0\0h+\7\0\0\0\0\0\0\0\0\0@\0008\0\7\0@\
0\33\0\32\0\1\0\0\0\5\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\
0\0\0\0\0\0\0\0<\f\7\0\0\0\0\0<\f\7\0\0\0\0\0\0\0
\0\0\0\0\0\1\0\0\0\6\0\0\0@\33\7\0\0\0\0\0@\33'\0\0\0\0\0@
\33'\0\0\0\0\0\350\4\0\0\0\0\0\0\310\5\0\0\0\0\0\0\0\0
\0\0\0\0\0\2\0\0\0\6\0\0\0\220\34\7\0\0\0\0\0\220\34'\0\
0\0\0\0\220\34'\0\0\0\0\0\0\2\0\0\0\0\0\0\0\2\0\0\0\0\0\0\
10\0\0\0\0\0\0\0\4\0\0\0\4\0\0\0\310\1\0\0\0\0\0\0\310\1\0\
0\0\0\0\0\310\1\0\0\0\0\0\0$\0\0\0\0\0\0\0$\0\0\0\0\0\0\0\4\0\0\0\0\0\0\0P\345td\4\0\0\0
\270\6\0\0\0\0\0 \270\6\0\0\0\0\0 \270\6\0\0\0\0\0\344\4\0\0\0\
0\0\0\344\4\0\0\0\0\0\0\4\0\0\0\0\0\0\0Q\345td\6\0\0\0\0\0\
0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\
0\0\0\0\0\0\0\0\20\0\0\0\0\0\0\0R\345td\4\0\0\0@\33\7\0\0\0\0\0@
\33'\0\0\0\0\0@\33'\0\0\0\0\0\300\4\0\0\0\0\0\0\300\4\
0\0\0\0\0\0\1\0\0\0\0\0\0\0\4\0\0\0\24\0\0\0\3\0\0\0GNU\0-\
270R\353O\252\33mA\275\234{\16\211\252\372X\273c\352\0\0\
0\0%\0\0\0\34\0\0\0\4\0\0\0\10\0\0\0B\204\t\0\202!\20\240\250@
\0\23\304\320\201\30\2\242X@\30\"D\v\200\30\10\1\">\
226\2\34\0\0\0\36\0\0\0\0\0\0\0\0\0\0\0 \0\0\0!\0\0\0\0\0\0\0\"\0\0\0\
0\0\0\0#\0\0\0\0\0\0\0%\0\0\0&\0\0\0(\0\0\0\0\0\0\0\0\0\0\0*
\0\0\0+\0\0\0-\0\0\0.\0\0\0\0\0\0\0\0\0\0\0/\0\0\0001\0\0\
0002\0\0\0004\0\0\0007\0\0\0\0\0\0\0\0\0\0\0008\0\0\0:\0\0\
0<\0\0\0\0\0\0\0=\0\0\0\0\0\0\0>\0\0\0?\0\0\0&\206t\2005\
317\205\323\270\201\272\305\21\177\27\257\225\273q3\353\
323\357\16q\\\374\177\364\354\2212{\374\266NcG\32s\362\371\
272n)\241H\16f\267\251\177o\316\312\205\227\17\332f\350\
341\354\224\223\351\373\177o\331\3621\211\236\254\16\3447\
234!\371\262E2\2552Vs\330qX\34\353\364\320\30\244vTi\272\
343\222|-S>\36EX[\373\350\f.p\1\23\365\350BE\325\354w\257\
326\223\355\7\262\236\313\207\345 \271\215\361\16", 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=471592, ...}) = 0
mmap(NULL, 2564360, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) =
0x7f8cda1fb000
mprotect(0x7f8cda26c000, 2097152, PROT_NONE) = 0
mmap(0x7f8cda46c000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE,
3, 0x71000) = 0x7f8cda46c000
close(3)                                = 0
open("/lib64/libsystemd.so.0", O_RDONLY|O_CLOEXEC) = 3

The command
*strace -ff -F -s200 -o /tmp/strace.out -p PID, con PID = root httpd
process, *
*during which I got a crash:*

select(0, NULL, NULL, NULL, {0, 850820}) = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396647, 863471}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 597518; Idle/Busy workers 99/0;Requests/sec: 10.2; Bytes
served/sec: 285KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396657, 892510}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 597706; Idle/Busy workers 99/0;Requests/sec: 10.2; Bytes
served/sec: 285KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396667, 914946}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 597834; Idle/Busy workers 99/0;Requests/sec: 10.2; Bytes
served/sec: 285KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396677, 935669}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 598275; Idle/Busy workers 99/0;Requests/sec: 10.2; Bytes
served/sec: 285KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396687, 958947}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 598470; Idle/Busy workers 98/1;Requests/sec: 10.2; Bytes
served/sec: 285KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396697, 979105}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 598736; Idle/Busy workers 98/2;Requests/sec: 10.2; Bytes
served/sec: 285KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396708, 16798}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 598954; Idle/Busy workers 99/0;Requests/sec: 10.2; Bytes
served/sec: 285KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396718, 49098}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 599033; Idle/Busy workers 98/1;Requests/sec: 10.2; Bytes
served/sec: 285KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396728, 73013}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 599198; Idle/Busy workers 99/0;Requests/sec: 10.2; Bytes
served/sec: 285KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396738, 97889}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 599267; Idle/Busy workers 100/0;Requests/sec: 10.2; Bytes
served/sec: 285KB/sec\n", 111}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 111
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396748, 119636}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 599531; Idle/Busy workers 98/2;Requests/sec: 10.2; Bytes
served/sec: 285KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396758, 144948}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 599666; Idle/Busy workers 98/1;Requests/sec: 10.2; Bytes
served/sec: 285KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396768, 166114}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 599916; Idle/Busy workers 99/0;Requests/sec: 10.2; Bytes
served/sec: 285KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396778, 195142}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 600004; Idle/Busy workers 99/0;Requests/sec: 10.2; Bytes
served/sec: 285KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396788, 222295}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 600178; Idle/Busy workers 98/1;Requests/sec: 10.2; Bytes
served/sec: 285KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396798, 244774}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 600421; Idle/Busy workers 100/0;Requests/sec: 10.2; Bytes
served/sec: 285KB/sec\n", 111}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 111
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396808, 272891}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 600651; Idle/Busy workers 99/0;Requests/sec: 10.2; Bytes
served/sec: 286KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396818, 291593}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 600961; Idle/Busy workers 100/0;Requests/sec: 10.2; Bytes
served/sec: 286KB/sec\n", 111}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 111
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396828, 319557}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 601256; Idle/Busy workers 99/0;Requests/sec: 10.2; Bytes
served/sec: 286KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396838, 337010}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 601485; Idle/Busy workers 98/2;Requests/sec: 10.2; Bytes
served/sec: 286KB/sec\n", 110}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 110
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
gettimeofday({1475396848, 360561}, NULL) = 0
socket(AF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 19
sendmsg(19, {msg_name(21)={sa_family=AF_LOCAL,
sun_path="/run/systemd/notify"}, msg_iov(1)=[{"READY=1\nSTATUS=Total
requests: 601686; Idle/Busy workers 100/0;Requests/sec: 10.2; Bytes
served/sec: 286KB/sec\n", 111}], msg_controllen=0, msg_flags=0},
MSG_NOSIGNAL) = 111
close(19)                               = 0
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0})     = 0 (Timeout)
wait4(-1, 0x7fff52971fbc, WNOHANG|WSTOPPED, NULL) = 0
select(0, NULL, NULL, NULL, {1, 0} <detached ...>




2016-10-02 10:02 GMT+02:00 Daniel <dferradal@gmail.com>:

> I would bet on your method of parsing php and the scripts being ran, in
> any case to be sure:
>
> Try the strace approach:
> strace -o /tmp/outputfile -s 5000 httpd -X or strace -ff -F -s200 -o
> /tmp/strace.out -p PID
>
> to try to find out what's really going on.
>
> 2016-09-30 20:19 GMT+02:00 Fabio F.Gervasi <fabio.gervasi70@gmail.com>:
>
>> Hi!
>>
>> I have additional information:
>>
>> /var/log/httpd/error_log, at "crash time": [Fri Sep 30 19:09:03.897325
>> 2016] [mpm_event:trace4] [pid 30339:tid 139796798162688] event.c(930):
>> socket reached timeout in lingering-close state
>>
>> What do you think?
>>
>>
>>
>> 2016-09-30 14:47 GMT+02:00 Fabio F.Gervasi <fabio.gervasi70@gmail.com>:
>>
>>> Hi!
>>>
>>> Thank you for your reply. I tried the following tests.
>>>
>>> *1) Using a little text file:*
>>> *# ab -k -c 100 -n 2000 localhost/test.txt*
>>> This is ApacheBench, Version 2.3 <$Revision: 1748469 $>
>>> Copyright 1996 Adam Twiss, Zeus Technology Ltd, http://www.zeustech.net/
>>> Licensed to The Apache Software Foundation, http://www.apache.org/
>>>
>>> Benchmarking localhost (be patient)
>>> Completed 200 requests
>>> Completed 400 requests
>>> Completed 600 requests
>>> Completed 800 requests
>>> Completed 1000 requests
>>> Completed 1200 requests
>>> Completed 1400 requests
>>> Completed 1600 requests
>>> Completed 1800 requests
>>> Completed 2000 requests
>>> Finished 2000 requests
>>>
>>>
>>> Server Software:        Apache/2.4.23
>>> Server Hostname:        localhost
>>> Server Port:            80
>>>
>>> Document Path:          /test.txt
>>> Document Length:        52 bytes
>>>
>>> Concurrency Level:      100
>>> Time taken for tests:   2.521 seconds
>>> Complete requests:      2000
>>> *Failed requests:        16*
>>>    (Connect: 0, Receive: 0, Length: 16, Exceptions: 0)
>>> Keep-Alive requests:    1984
>>> Total transferred:      950336 bytes
>>> HTML transferred:       103168 bytes
>>> Requests per second:    793.45 [#/sec] (mean)
>>> Time per request:       126.032 [ms] (mean)
>>> Time per request:       1.260 [ms] (mean, across all concurrent requests)
>>> Transfer rate:          368.18 [Kbytes/sec] received
>>>
>>> Connection Times (ms)
>>>               min  mean[+/-sd] median   max
>>> Connect:        0    1   3.8      0      21
>>> Processing:     1  123 126.5     83     772
>>> Waiting:        0  123 126.7     83     772
>>> Total:          1  124 126.6     85     772
>>>
>>> Percentage of the requests served within a certain time (ms)
>>>   50%     85
>>>   66%    154
>>>   75%    201
>>>   80%    229
>>>   90%    303
>>>   95%    373
>>>   98%    452
>>>   99%    547
>>>  100%    772 (longest request)
>>>
>>> *I obtain 16 failed requests, but if I run more again I obtain a
>>> different number.*
>>>
>>> *2) Using a big gif file:*
>>> *# ab -k -c 100 -n 2000 localhost/it-vis-animation.gif*
>>> This is ApacheBench, Version 2.3 <$Revision: 1748469 $>
>>> Copyright 1996 Adam Twiss, Zeus Technology Ltd, http://www.zeustech.net/
>>> Licensed to The Apache Software Foundation, http://www.apache.org/
>>>
>>> Benchmarking localhost (be patient)
>>> Completed 200 requests
>>> Completed 400 requests
>>> Completed 600 requests
>>> Completed 800 requests
>>> Completed 1000 requests
>>> Completed 1200 requests
>>> Completed 1400 requests
>>> Completed 1600 requests
>>> Completed 1800 requests
>>> Completed 2000 requests
>>> Finished 2000 requests
>>>
>>>
>>> Server Software:        Apache/2.4.23
>>> Server Hostname:        localhost
>>> Server Port:            80
>>>
>>> Document Path:          /it-vis-animation.gif
>>> Document Length:        8105309 bytes
>>>
>>> Concurrency Level:      100
>>> Time taken for tests:   26.294 seconds
>>> Complete requests:      2000
>>> *Failed requests:        0*
>>> Keep-Alive requests:    2000
>>> Total transferred:      16211484000 bytes
>>> HTML transferred:       16210618000 bytes
>>> Requests per second:    76.06 [#/sec] (mean)
>>> Time per request:       1314.691 [ms] (mean)
>>> Time per request:       13.147 [ms] (mean, across all concurrent
>>> requests)
>>> Transfer rate:          602100.83 [Kbytes/sec] received
>>>
>>> Connection Times (ms)
>>>               min  mean[+/-sd] median   max
>>> Connect:        0    1   4.0      0      22
>>> Processing:   404 1309  80.0   1298    1635
>>> Waiting:        5   22  32.4     10     207
>>> Total:        420 1310  80.6   1298    1635
>>>
>>> Percentage of the requests served within a certain time (ms)
>>>   50%   1298
>>>   66%   1315
>>>   75%   1342
>>>   80%   1360
>>>   90%   1397
>>>   95%   1440
>>>   98%   1513
>>>   99%   1530
>>>  100%   1635 (longest request)
>>>
>>> *In this case I obtain always 0 failed!*
>>>
>>> *3) Using a little png file:*
>>> *# ab -k -c 100 -n 2000 localhost/apple-touch-icon.png*
>>> This is ApacheBench, Version 2.3 <$Revision: 1748469 $>
>>> Copyright 1996 Adam Twiss, Zeus Technology Ltd, http://www.zeustech.net/
>>> Licensed to The Apache Software Foundation, http://www.apache.org/
>>>
>>> Benchmarking localhost (be patient)
>>> Completed 200 requests
>>> Completed 400 requests
>>> Completed 600 requests
>>> Completed 800 requests
>>> Completed 1000 requests
>>> Completed 1200 requests
>>> Completed 1400 requests
>>> Completed 1600 requests
>>> Completed 1800 requests
>>> Completed 2000 requests
>>> Finished 2000 requests
>>>
>>>
>>> Server Software:        Apache/2.4.23
>>> Server Hostname:        localhost
>>> Server Port:            80
>>>
>>> Document Path:          /apple-touch-icon.png
>>> Document Length:        12364 bytes
>>>
>>> Concurrency Level:      100
>>> Time taken for tests:   2.576 seconds
>>> Complete requests:      2000
>>> *Failed requests:        22*
>>>    (Connect: 0, Receive: 0, Length: 22, Exceptions: 0)
>>> Keep-Alive requests:    1978
>>> Total transferred:      25304554 bytes
>>> HTML transferred:       24455992 bytes
>>> Requests per second:    776.52 [#/sec] (mean)
>>> Time per request:       128.779 [ms] (mean)
>>> Time per request:       1.288 [ms] (mean, across all concurrent requests)
>>> Transfer rate:          9594.53 [Kbytes/sec] received
>>>
>>> Connection Times (ms)
>>>               min  mean[+/-sd] median   max
>>> Connect:        0    1   3.9      0      20
>>> Processing:     0  125 112.0    101     563
>>> Waiting:        0  125 112.1    101     563
>>> Total:          0  126 112.6    102     563
>>>
>>> Percentage of the requests served within a certain time (ms)
>>>   50%    102
>>>   66%    159
>>>   75%    196
>>>   80%    220
>>>   90%    282
>>>   95%    354
>>>   98%    401
>>>   99%    457
>>>  100%    563 (longest request)
>>>
>>> *I get 22 failed requests, but if I run more again I obtain a different
>>> number.*
>>>
>>> What do you thing?
>>>
>>> Thank you!
>>>
>>>
>>>
>>> 2016-09-30 13:50 GMT+02:00 Daniel <dferradal@gmail.com>:
>>>
>>>> hello,
>>>>
>>>> the fact that all those 500 error reponses go with "X-Powered-By:
>>>> PHP/5.6.26" tell me it is php giving those errors.
>>>>
>>>> If you are still unsure, you could use the ab tool from Apache HTTPD,
>>>> and request a specific static file (text, gif) hundreds of times to see if
>>>> you get a 500 error from HTTPD.
>>>>
>>>> 2016-09-30 8:59 GMT+02:00 Fabio F.Gervasi <fabio.gervasi70@gmail.com>:
>>>>
>>>>> Hi,
>>>>>
>>>>> I'm not 100% sure that the real source of the problem is apache (could
>>>>> be php or other), but I'd like start from here as the only logs that
>>>>> provides me with information (with trace4 level) is apache log. No
>>>>> information appears from other logs.
>>>>>
>>>>> I have:
>>>>> - Linux 4.7.4-100.fc23.x86_64
>>>>> - Server version: Apache/2.4.23 (Fedora)
>>>>> - mysql  Ver 15.1 Distrib 10.0.26-MariaDB, for Linux (x86_64) using
>>>>> readline 5.1
>>>>> - PHP Version => 5.6.25
>>>>>
>>>>> The problem: random internal server error 500. Random because, for
>>>>> example, if a page produces the error and do "reload" then it works. The
>>>>> same page may not work as well as work. Sometimes you can use the site for
>>>>> several minutes with no problems, then suddenly there is the error.
>>>>>
>>>>> When error occurs, this is all that I get from the log file (with
>>>>> loglevel = trace4):
>>>>>
>>>>> [...]
>>>>> [Fri Sep 30 07:41:39.151052 2016] [rewrite:trace3] [pid 17957:tid
>>>>> 140241771534080] mod_rewrite.c(477): [client 151.25.206.200:38368]
>>>>> 151.25.206.200 - - [www.centrometeo.com/sid#55c3e
>>>>> 3673400][rid#7f8c8000e9d0/subreq
>>>>> <http://www.centrometeo.com/sid#55c3e3673400][rid%237f8c8000e9d0/subreq>]
>>>>> [perdir /home/web/centrometeo.com/] applying pattern '.*' to uri
>>>>> 'index.php', referer: http://www.centrometeo.com/mod
>>>>> elli-numerici/modello-wrf-nmm/559-wrf-mslp-6hprec-ita
>>>>> [Fri Sep 30 07:41:39.151092 2016] [rewrite:trace1] [pid 17957:tid
>>>>> 140241771534080] mod_rewrite.c(477): [client 151.25.206.200:38368]
>>>>> 151.25.206.200 - - [www.centrometeo.com/sid#55c3e
>>>>> 3673400][rid#7f8c8000e9d0/subreq
>>>>> <http://www.centrometeo.com/sid#55c3e3673400][rid%237f8c8000e9d0/subreq>]
>>>>> [perdir /home/web/centrometeo.com/] pass through /home/web/
>>>>> centrometeo.com/index.php, referer: http://www.centrometeo.com/mod
>>>>> elli-numerici/modello-wrf-nmm/559-wrf-mslp-6hprec-ita
>>>>> [Fri Sep 30 07:41:39.913003 2016] [core:trace4] [pid 17957:tid
>>>>> 140241771534080] util_script.c(567): [client 151.25.206.200:38368]
>>>>> Headers from script 'php.fcgi':, referer:
>>>>> http://www.centrometeo.com/modelli-numerici/modello-wrf-nmm/
>>>>> 559-wrf-mslp-6hprec-ita
>>>>> [Fri Sep 30 07:41:39.913097 2016] [core:trace4] [pid 17957:tid
>>>>> 140241771534080] util_script.c(568): [client 151.25.206.200:38368]   *Status:
>>>>> 500 Internal Server Error*, referer: http://www.centrometeo.com/mod
>>>>> elli-numerici/modello-wrf-nmm/559-wrf-mslp-6hprec-ita
>>>>> [Fri Sep 30 07:41:39.913130 2016] [core:trace1] [pid 17957:tid
>>>>> 140241771534080] util_script.c(649): [client 151.25.206.200:38368] *Status
>>>>> line from script 'php.fcgi': 500 Internal Server Error*, referer:
>>>>> http://www.centrometeo.com/modelli-numerici/modello-wrf-nmm/
>>>>> 559-wrf-mslp-6hprec-ita
>>>>> [Fri Sep 30 07:41:39.913162 2016] [core:trace4] [pid 17957:tid
>>>>> 140241771534080] util_script.c(568): [client 151.25.206.200:38368]
>>>>> X-Powered-By: PHP/5.6.26, referer: http://www.centrometeo.com/mod
>>>>> elli-numerici/modello-wrf-nmm/559-wrf-mslp-6hprec-ita
>>>>> [Fri Sep 30 07:41:39.913191 2016] [core:trace4] [pid 17957:tid
>>>>> 140241771534080] util_script.c(568): [client 151.25.206.200:38368]
>>>>> P3P: CP="NOI ADM DEV PSAi COM NAV OUR OTRo STP IND DEM", referer:
>>>>> http://www.centrometeo.com/modelli-numerici/modello-wrf-nmm/
>>>>> 559-wrf-mslp-6hprec-ita
>>>>> [Fri Sep 30 07:41:39.913218 2016] [core:trace4] [pid 17957:tid
>>>>> 140241771534080] util_script.c(568): [client 151.25.206.200:38368]
>>>>> Content-Encoding: gzip, referer: http://www.centrometeo.com/mod
>>>>> elli-numerici/modello-wrf-nmm/559-wrf-mslp-6hprec-ita
>>>>> [Fri Sep 30 07:41:39.913246 2016] [core:trace4] [pid 17957:tid
>>>>> 140241771534080] util_script.c(568): [client 151.25.206.200:38368]
>>>>> Vary: Accept-Encoding, referer: http://www.centrometeo.com/mod
>>>>> elli-numerici/modello-wrf-nmm/559-wrf-mslp-6hprec-ita
>>>>> [Fri Sep 30 07:41:39.913275 2016] [core:trace4] [pid 17957:tid
>>>>> 140241771534080] util_script.c(568): [client 151.25.206.200:38368]
>>>>> Content-type: text/html; charset=UTF-8, referer:
>>>>> http://www.centrometeo.com/modelli-numerici/modello-wrf-nmm/
>>>>> 559-wrf-mslp-6hprec-ita
>>>>> [Fri Sep 30 07:41:39.913395 2016] [http:trace3] [pid 17957:tid
>>>>> 140241771534080] http_filters.c(1006): [client 151.25.206.200:38368] *Response
>>>>> sent with status 500, headers:*, referer:
>>>>> http://www.centrometeo.com/modelli-numerici/modello-wrf-nmm/
>>>>> 559-wrf-mslp-6hprec-ita
>>>>> [Fri Sep 30 07:41:39.913432 2016] [http:trace4] [pid 17957:tid
>>>>> 140241771534080] http_filters.c(835): [client 151.25.206.200:38368]
>>>>> X-Powered-By: PHP/5.6.26, referer: http://www.centrometeo.com/mod
>>>>> elli-numerici/modello-wrf-nmm/559-wrf-mslp-6hprec-ita
>>>>> [Fri Sep 30 07:41:39.913458 2016] [http:trace4] [pid 17957:tid
>>>>> 140241771534080] http_filters.c(835): [client 151.25.206.200:38368]
>>>>> P3P: CP=\\"NOI ADM DEV PSAi COM NAV OUR OTRo STP IND DEM\\", referer:
>>>>> http://www.centrometeo.com/modelli-numerici/modello-wrf-nmm/
>>>>> 559-wrf-mslp-6hprec-ita
>>>>> [Fri Sep 30 07:41:39.913481 2016] [http:trace4] [pid 17957:tid
>>>>> 140241771534080] http_filters.c(835): [client 151.25.206.200:38368]
>>>>> Content-Encoding: gzip, referer: http://www.centrometeo.com/mod
>>>>> elli-numerici/modello-wrf-nmm/559-wrf-mslp-6hprec-ita
>>>>> [...]
>>>>>
>>>>> Note the use of fcgi. Apache is indeed configured with
>>>>> event/php-fpm/FastCGI, but the same thing happens with "normal" prefork /
>>>>> php!
>>>>> Until a few days ago it did not happen, it started after updating the
>>>>> system (kernel, apache, php, mariadb).
>>>>> I do not really know what to do.
>>>>>
>>>>> Thank you very much.
>>>>> Fabio.
>>>>>
>>>>
>>>>
>>>>
>>>> --
>>>> *Daniel Ferradal*
>>>> IT Specialist
>>>>
>>>> email         dferradal at gmail.com
>>>> linkedin     es.linkedin.com/in/danielferradal
>>>>
>>>
>>>
>>
>
>
> --
> *Daniel Ferradal*
> IT Specialist
>
> email         dferradal at gmail.com
> linkedin     es.linkedin.com/in/danielferradal
>

Mime
View raw message