Bloqueio de sessão após usar Cm_RedisSession

9

Mudamos para Redis como armazenamento de sessão com o módulo Cm_RedisSession padrão do Magento 1.9.2.4. Após a implantação, muitos clientes tiveram tempos de carregamento de página muito longos (> 20 a 30 segundos). Para o Redis-Server, estamos usando o AWS ElastiCache (m3.large).
No Tideways (semelhante ao Newrelic), vi esse gargalo no rastreamento:

Rastreamento das marés

Depois de ler mais sobre esse problema e examinar o log do Cm_RedisSession, concluí que a sessão do cliente estava bloqueada e, após mais pesquisas, decidi atualizar o Cm_RedisSession para a versão 1.14, devido às melhorias no bloqueio da sessão.

Com a versão mais recente, o problema é minimizado, porque o bloqueio agora será interrompido corretamente após 5 segundos. Mas ainda há um tempo de carregamento de 5 segundos.

Eu tinha duas teorias.

  1. Algumas solicitações morrem, portanto não há session_close()chamadas e, por esse motivo, o bloqueio não será liberado:

    Habilitei todos os logs (php-fpm, nginx e magento) e os observei até que esse erro apareça no Tideways for a Customer, mas não houve erro nesse período específico

  2. Vários scripts tentam ler / gravar a mesma sessão:

    Criei um script que chama paralelamente cem vezes a mesma página com o mesmo cookie de interface, mas nenhum bloqueio aparece.

Neste ponto, não consigo descobrir por que esse bloqueio aparece e, pior ainda, não consigo reproduzi-lo no Maschine local ou no sistema de armazenamento temporário.

Alguém tem uma dica ou solução de como resolver esse problema?

Edit : alguém tentou desativar o bloqueio em Cm_RedisSession?

Edit : mesmo problema com 1.15

Editar : a maioria dos pedidos com um bloqueio é ajax. Mas não posso reproduzi-lo de qualquer maneira.


$ php5-fpm -v

PHP 5.5.32-1+deb.sury.org~trusty+1 (fpm-fcgi) (built: Feb  5 2016 10:10:42)
  Copyright (c) 1997-2015 The PHP Group
  Zend Engine v2.5.0, Copyright (c) 1998-2015 Zend Technologies
    with Zend OPcache v7.0.6-dev, Copyright (c) 1999-2015, by Zend Technologies

$ nginx -v

nginx version: nginx/1.8.1

local.xml

<redis_session>                       
    <host>***************</host>            
    <port>****</port>
    <password></password>             
    <timeout>2.5</timeout>            
    <persistent></persistent>         
    <db>0</db>                        
    <compression_threshold>2048</compression_threshold>  
    <compression_lib>gzip</compression_lib>              
    <log_level>1</log_level>               
    <max_concurrency>6</max_concurrency>                 
    <break_after_frontend>5</break_after_frontend>       
    <break_after_adminhtml>30</break_after_adminhtml>
    <first_lifetime>600</first_lifetime>                 
    <bot_first_lifetime>60</bot_first_lifetime>          
    <bot_lifetime>7200</bot_lifetime>                    
    <disable_locking>0</disable_locking>                 
    <min_lifetime>60</min_lifetime>                      
    <max_lifetime>2592000</max_lifetime>                 
</redis_session>

INFOTela Redis :

$1939
# Server
redis_version:2.8.24
redis_git_sha1:0
redis_git_dirty:0
redis_build_id:0
redis_mode:standalone
os:Amazon ElastiCache
arch_bits:64
multiplexing_api:epoll
gcc_version:0.0.0
process_id:1
run_id:fbf620d695c006bdb570c05b104404eb8f2c12aa
tcp_port:6379
uptime_in_seconds:1140502
uptime_in_days:13
hz:10
lru_clock:12531431
config_file:/etc/redis.conf

# Clients
connected_clients:8
client_longest_output_list:0
client_biggest_input_buf:0
blocked_clients:0

# Memory
used_memory:2586086144
used_memory_human:2.41G
used_memory_rss:2637590528
used_memory_peak:2586312888
used_memory_peak_human:2.41G
used_memory_lua:36864
mem_fragmentation_ratio:1.02
mem_allocator:jemalloc-3.6.0

# Persistence
loading:0
rdb_changes_since_last_save:18525202
rdb_bgsave_in_progress:0
rdb_last_save_time:1471008721
rdb_last_bgsave_status:ok
rdb_last_bgsave_time_sec:-1
rdb_current_bgsave_time_sec:-1
aof_enabled:0
aof_rewrite_in_progress:0
aof_rewrite_scheduled:0
aof_last_rewrite_time_sec:-1
aof_current_rewrite_time_sec:-1
aof_last_bgrewrite_status:ok
aof_last_write_status:ok

# Stats
total_connections_received:1518441
total_commands_processed:28898066
instantaneous_ops_per_sec:14
total_net_input_bytes:7409376406
total_net_output_bytes:3059470870
instantaneous_input_kbps:3.10
instantaneous_output_kbps:0.78
rejected_connections:0
sync_full:0
sync_partial_ok:0
sync_partial_err:0
expired_keys:420590
evicted_keys:0
keyspace_hits:8754547
keyspace_misses:18323
pubsub_channels:0
pubsub_patterns:0
latest_fork_usec:0

# Replication
role:master
connected_slaves:0
master_repl_offset:322498
repl_backlog_active:0
repl_backlog_size:1048576
repl_backlog_first_byte_offset:2795
repl_backlog_histlen:319704

# CPU
used_cpu_sys:729.42
used_cpu_user:509.25
used_cpu_sys_children:0.00
used_cpu_user_children:0.00

# Keyspace
db0:keys=1413298,expires=1413297,avg_ttl=1780138273
Pawel
fonte
11
Cm_RedisSession está incluído no código principal do Magento 1.9.x, mas na verdade é desenvolvido por Colin Mollenhour. Você está usando o código do módulo Cm_RedisSession incluído no 1.9.2.4 ou a versão mais recente do GitHub github.com/colinmollenhour/Cm_RedisSession ?
paj 12/09
Como escrevi, atualizamos para a versão mais recente
Pawel
Você vê o mesmo problema se você executar o servidor Redis localmente
paj
11
Estou rastreando exatamente o mesmo problema. Nós experimentamos esse MemCache pela primeira vez e nos mudamos para Redis com a esperança de ganhar mais visibilidade. Estamos usando o 1.14.2 com Apache 2.x. Usando o monitor redis-cli, consegui identificar se as solicitações estão bloqueando a sessão e não a desbloqueando. Ainda não determinamos por que uma pequena porcentagem de nossos pedidos faz isso (cerca de 50 a 100 por hora durante o pico do dia).
Craig Harris
11
magento.stackexchange.com/a/130691/69 Uma pergunta semelhante, mas pode oferecer algumas opções / ferramentas para usar na depuração.
B00MER 15/09/16

Respostas:

6

Parece que eliminei principalmente nossos problemas. No entanto, nunca realmente determinei a causa exata.

Após a atualização da versão mais recente do Cm_RedisSession, o log indicou que 95% das solicitações que estavam mantendo a sessão deveriam realmente ser sem estado. Eu implementei FLAG_NO_START_SESSION no preDispatch () para evitar que o Magento crie sessões. Fiquei muito surpreso ao descobrir que, na produção, os pedidos agora "sem estado" ainda mantinham 95% dos bloqueios da sessão. Investigações posteriores descobriram que tínhamos alguns observadores que estavam disparando e ainda tentavam iniciar a sessão. Depois que eles foram atualizados para também honrar o FLAG_NO_START_SESSION, nosso problema de bloqueio de sessão foi quase totalmente removido.

Não acho que isso resolva o problema, mas espero que outros possam usar uma técnica semelhante.

Craig Harris
fonte
Acho que a solicitação de estado sem estado não funciona para nós, porque essas solicitações precisam da sessão.
Pawel