Mysql completing Query fast but mysql->query() takes long time to return even single selects/upda
am 09.04.2010 16:43:24 von David Murphy------=_NextPart_000_007F_01CAD7C9.199AE3A0
Content-Type: text/plain;
charset="US-ASCII"
As you can see PHP claims it took 20 seconds for mysql->query() to return
but mysql think is took around 1.0s
This is from our application
I enabled profile in mysql to determine why an update took 20seconds. As
you can see MySQL reported no where near that amount of duration took
place.
Is there any way I can dig into php and determine why mysql client libs are
so slow (this is not using mysqlnd but mysql-client-libs on CentOS using
5.3.2)
04/06/2010 14:54:54 20.6899s UPDATE `calls` SET `Result`='Busy' WHERE
`CallID`='144786'
| Status | Duration | CPU_user | CPU_system |
Context_voluntary | Context_involuntary | Block_ops_in | Block_ops_out |
Messages_sent | Messages_received | Page_faults_major | Page_faults_minor |
Swaps |
------------------------------------------------------------ ----------------
------------------------------------------------------------ ----------------
------------------------------------------------------------ ------
| starting | 0.000039 | 0.000000 | 0.000000 | 0
| 0 | 0 | 0 | 0 | 0
| 0 | 0 | 0 |
| checking permissions | 0.000008 | 0.000000 | 0.000000 | 0
| 0 | 0 | 0 | 0 | 0
| 0 | 0 | 0 |
| Opening tables | 0.000010 | 0.000000 | 0.000000 | 0
| 0 | 0 | 0 | 0 | 0
| 0 | 0 | 0 |
| System lock | 0.000005 | 0.000000 | 0.000000 | 0
| 0 | 0 | 0 | 0 | 0
| 0 | 0 | 0 |
| Table lock | 0.000006 | 0.000000 | 0.000000 | 0
| 0 | 0 | 0 | 0 | 0
| 0 | 0 | 0 |
| init | 0.000036 | 0.000000 | 0.000000 | 0
| 0 | 0 | 0 | 0 | 0
| 0 | 0 | 0 |
| Updating | 0.000099 | 0.001000 | 0.000000 | 0
| 0 | 0 | 0 | 0 | 0
| 0 | 0 | 0 |
| end | 0.000023 | 0.000000 | 0.000000 | 0
| 0 | 0 | 0 | 0 | 0
| 0 | 0 | 0 |
| query end | 0.000004 | 0.000000 | 0.000000 | 0
| 0 | 0 | 0 | 0 | 0
| 0 | 0 | 0 |
| freeing items | 0.007410 | 0.000000 | 0.000000 | 4
| 1 | 0 | 0 | 0 | 0
| 0 | 0 | 0 |
| logging slow query | 0.000004 | 0.000000 | 0.000000 | 0
| 0 | 0 | 0 | 0 | 0
| 0 | 0 | 0 |
| cleaning up | 0.000004 | 0.000000 | 0.000000 | 0
| 0 | 0 | 0 | 0 | 0
| 0 | 0 | 0 |
This is to a remote system ( but on same GigE switch), however mysql
profiling would log transit type if this was a select .
Thanks
David Murphy
------=_NextPart_000_007F_01CAD7C9.199AE3A0--