MySQL Execution Time Spikes

Posted by Brett on Server Fault See other posts from Server Fault or by Brett
Published on 2012-05-08T22:14:47Z Indexed on 2012/06/20 21:18 UTC
Read the original article Hit count: 463

Filed under:
|
|
|

I am having issues with MySQL all of the sudden today.

Details:

  • OS: CentOS release 5.7
  • Server type: Parallels virtuozzo container running on mediatemple DV 4.0 package
  • Average total memory usage: <500mb
  • Total memory usage allowed: 1gb (part of shared pool for emergency only, users are only guaranteed 500mb)
  • Processor: >1ghz
  • Main database sizes with most usage: 275mb & 107mb
  • server stack: nginx 1.0.10, mysql 5.1.54, php 5.3.8 with php-fpm
  • innodb_buffer_pool_size=100M
  • php-fpm max children: 5
  • Webapps: custom php-based sites, magento & drupal
  • slow query timeout is set to 1 second

Steps I completed towards diagnosis:

  1. Cannot restart container yet - I will try later tonight when our domestic traffic has dropped
  2. Enabled mysql and php-fpm slowlog.
  3. Found functions that did DB queries in php-fpm slowlog were taking over 1s to complete at times
  4. Found some simple queries in mysql slowlog taking well over 1s to complete that should take less than 1s.
  5. Most interesting - execution time seems to spike at times. A query will take .2s a couple times, then one time it will take 8s to run the same query. These results were verified by running raw SQL queries through mysql command line.
  6. Top does not reveal anything too interesting
  7. Only resource related thing i can see is load averages much higher than normal
  8. Up until today, mysql has been fine, there have been no major changes to the db since yesterday.
  9. Sometimes things are so bad, I am seeing bad gateway errors after 60s of execution time.
  10. Innodb is doing on average 300-1400 reads/sec.
  11. Mysql is doing 3-10 queries/sec
  12. slow query count in 2 hours uptime is 171 (with slow timeout at 1 second)
  13. Tried restarting mysql, nginx, php-fpm multiple times

For example:

UPDATE  `catalogsearch_query` SET `query_text` = 'EW 90', `num_results` = '7532', `popularity` = '99180', `redirect` = NULL, `synonym_for` = NULL, `store_id` = '1', `display_in_terms` = '1', `is_active` = '1', `is_processed` = '1', `updated_at` =  '2012-05-08 21:38:31' WHERE (query_id='31');

This query took 17sec to complete one time, rest of the time around .079 sec. But varies, sometimes 1sec, sometimes .004 sec. This is running the same query, over and over with a couple seconds time in between each.

Most tables are innodb, and sometimes I noticed the lock time taking 90% of the query execution time, but most of the time lock time is insignificant.

Any idea what's going on here?

© Server Fault or respective owner

Related posts about mysql

Related posts about php