Segfault PHP-FPM, Nginx, Freebsd

Question

Estamos enfrentando falta de CPU com carga alta. Isso só acontece em algum momento quando os usuários on-line estão por volta de 1300-1400 (de acordo com o google analytics). Estamos recebendo resposta vazia no navegador.

Parece estranho para mim que a falta aconteça inesperadamente. Quero dizer - há mais de 50% de CPU inativa e, de repente, ela cai para 0% por vários segundos e depois volta.

Aqui está a saída "iostat 1" para esse momento. Veja a última coluna. 70% é o comportamento normal mais recente. link

Todos os processos do PHP-FPM na fila estão morrendo, deixando esses logs em / var / log / messages

Feb 25 20:20:01 <kern.info> dasaran kernel: pid 36267 (php-fpm), uid 80: exited on signal 11
Feb 25 20:20:01 <kern.info> dasaran kernel: pid 36265 (php-fpm), uid 80: exited on signal 11
Feb 25 20:20:01 <kern.info> dasaran kernel: pid 36263 (php-fpm), uid 80: exited on signal 11
Feb 25 20:20:01 <kern.info> dasaran kernel: pid 36262 (php-fpm), uid 80: exited on signal 11

Log de erros do Nginx

2013/02/25 20:22:14 [error] 34877#0: accept() failed (53: Software caused connection abort)
2013/02/25 20:22:14 [error] 34877#0: accept() failed (53: Software caused connection abort)
2013/02/25 20:22:14 [error] 34877#0: accept() failed (53: Software caused connection abort)
2013/02/25 20:22:14 [error] 34877#0: accept() failed (53: Software caused connection abort)
2013/02/25 20:22:14 [error] 34874#0: accept() failed (53: Software caused connection abort)

Eu não entendo esse comportamento por dois motivos.

1. Se a carga está causando falta de CPU, a CPU não deve diminuir linearmente, não de repente? Mas outro fato é que isso acontece na carga.
2. Por que 0% ocioso dura vários segundos?

Nós tentamos otimizar os scripts, servidor e banco de dados (servidor separado). Apenas deu um aumento menor de usuários de pico.

Configuração do servidor:

FreeBSD 8.3
Intel® Xeon® E3-1245 Quadcore
32 GB ECC RAM

O que pode causar problemas como este? Quais estratégias devo praticar para encontrar o gargalo?

UPDATE Aqui está a saída do gdb para bt e dump_bt.

(gdb) dump_bt executor_globals.current_execute_data
[0x801827a58] getSaveHandler() /www/svn/zend-libs/Toktik/Session/Set.php:42
[0x8018278d0] Toktik_Session_Set::getSaveHandler() /www/svn/zend-libs/Toktik/Session/Set.php:59
[0x801827630] Toktik_Session_Set->add("6j6omknh8tbr28358gadtp40s7") /www/svn/zend-libs/Toktik/Session/SaveHandler/Phpredis.php:146
[0x7fffffffc350] Toktik_Session_SaveHandler_Phpredis->write("6j6omknh8tbr28358gadtp40s7", "Zend_Auth|a:1:{s:7:"storage";s:7:"3963623";}")
(gdb) bt
#0 0x0000000000695cfe in zend_fetch_var_address_helper_SPEC_CONST_VAR (type=0, execute_data=0x801827a58)
at zend_vm_execute.h:4836
#1 0x00000000006961da in ZEND_FETCH_R_SPEC_CONST_VAR_HANDLER (execute_data=0x801827a58)
at zend_vm_execute.h:4863
#2 0x0000000000680a01 in execute (op_array=0x80dc8e2c8) at zend_vm_execute.h:410
#3 0x000000000063101d in zend_call_function (fci=0x7fffffffc640, fci_cache=0x7fffffffc320)
at /usr/ports/lang/php5/work/php-5.4.10/Zend/zend_execute_API.c:958
#4 0x000000000062fe8a in call_user_function_ex (function_table=0x80185e060, object_pp=0x0,
function_name=0x80dc99b78, retval_ptr_ptr=0x7fffffffc6e8, param_count=2, params=0x80dadcab0,
no_separation=1, symbol_table=0x0) at /usr/ports/lang/php5/work/php-5.4.10/Zend/zend_execute_API.c:750
#5 0x000000000062fcbd in call_user_function (function_table=0x80185e060, object_pp=0x0,
function_name=0x80dc99b78, retval_ptr=0x80dae2670, param_count=2, params=0x7fffffffc7a0)
at /usr/ports/lang/php5/work/php-5.4.10/Zend/zend_execute_API.c:723
#6 0x0000000803cc924f in ps_call_handler () from /usr/local/lib/php/20100525-debug/session.so
#7 0x0000000803cc9924 in ps_write_user () from /usr/local/lib/php/20100525-debug/session.so
#8 0x0000000803cbf4a8 in php_session_save_current_state () from /usr/local/lib/php/20100525-debug/session.so
#9 0x0000000803cc3d06 in php_session_flush () from /usr/local/lib/php/20100525-debug/session.so
#10 0x0000000803cc5cd9 in zm_deactivate_session () from /usr/local/lib/php/20100525-debug/session.so
#11 0x000000000064f121 in zend_deactivate_modules () at /usr/ports/lang/php5/work/php-5.4.10/Zend/zend_API.c:2335
#12 0x00000000005b8aea in php_request_shutdown (dummy=0x0)
at /usr/ports/lang/php5/work/php-5.4.10/main/main.c:1759
#13 0x000000000079ec06 in main (argc=1, argv=0x7fffffffed58)
at /usr/ports/lang/php5/work/php-5.4.10/sapi/fpm/fpm/fpm_main.c:1948

Aqui estão as classes responsáveis pelo gerenciamento de sessões usando phpredis (dump_bt aponta para estes) link

link

    

Answer 1

Desculpe não uma resposta adequada, mas um pouco longa demais para um comentário.

O que a pilha está dizendo é que o mecanismo de execução surgiu em um retorno de chamada do usuário durante o encerramento da sessão. Foi quando o manipulador de sessão Toktik_Session_SaveHandler_Phpredis :: write lançou uma exceção na linha 146. Não sei por que o add falhou, mas lançar exceções durante o resumo da imagem é uma má ideia. Então

• Por que o acréscimo está falhando? Você tem alguma forma de sobrecarga de atualização que causa sobrecarga / tempo limite de fila.
• Considere apenas engolir isso e perder essa sessão;