Problème de performance à la connexion

Version imprimable

23/03/2009, 21h05
Baud10

Problème de performance à la connexion

Bonjour

(désolé je n'ai pas vraiment trouvé de rubrique où poster ce message)

J'affiche en bas de mes pages le temps qu'a été utilisé pour la génération de celle-ci. Je suis étonné de voir un temps très différent en ayant changé de config :

Mon site était hébergé chez OVH mutualisé start10G. Je suis maintenant hébergé chez OVH dédié RPS I.

La fautive est mysql_connect().
Cette fonction PHP met 13.8 ms en moyenne a s'exécuter sur ma nouvelle config la dédié, contre seulement 4 ms sur l'ancienne.

Pourtant, le serveur MySQL 5.0.44 est installé en local sur le serveur. La machine est équipée d'un Intel Atom 230 à 1.6 GHz Hyperthreadé, Linux gentoo.
C'est surement beaucoup moins que les double-be-Xeon des mutualisés...

Mais ma question était: est-ce ma configuration matérielle qui explique cette différence de temps ? ou un problème dans mes paramètres apache/php/mysql... ?

Comment le mesurer ? Comment Optimiser ?

Est-ce que cette différence (de 10 ms) est de l'attente passive (CPU qui attend un évènement) ou il travail ... ?

Vous allez me dire qu'on ne va pas rechigner pour 10 ms ? Entre une page qui met 10ms et 20ms au total, ça fait techniquement 2x plus de visiteurs simultanés possible ! J'avoue c'est également une question par curiosité, étonnant d'une telle disparité :)

A noter que le reste des requêtes s'effectuent dans un temps correcte. L'utilisation de mysql_pconnect ne change rien. L'utilisation du chemin de la socket plutot que "localhost" ne change rien.
23/03/2009, 21h55
StephaneC.

Citation:

Envoyé par Baud10

L'utilisation du chemin de la socket plutot que "localhost" ne change rien.

Oui, c'est normal, sous Linux, quand on indique localhost, la connexion se fait par défaut avec une socket. Si tu veux utiliser TCP, il faut préciser 127.0.0.1. Tu peux toujours faire le test, bien que les sockets sont réputées plus rapides que les connexions TCP.

Et pour ton problème de mysql_connect(), as-tu installé un débugger (Xdebug par exemple) qui pourrait te donner plus d'infos ?

As-tu un changement dans ton my.cnf comme le thread_cache_size ?

Stéphane
23/03/2009, 22h05
Baud10

Mes tests de comparaisons étaient tous basés sur le nom d'hôte du serveur MySQL => donc en socket. La différence ne devrait pas être donc du a ça. Néanmoins, j'avais essayé avec 127.0.0.1, mais la connexion échoue : "Lost connection to MySQL server at 'reading initial communication packet', system error: 111". En spéficiant ":/var/run/mysqld/mysqld.sock" ça fonctionne mais j'ai exactement le même temps.

Je veux bien essayer de déboguer mais ni les logs d'apache ni de mysql ne contiennent quelque chose de probant. Je vais voir quand même.

Je n'ai pas de directive "thread_cache_size" dans mon my.cnf.
24/03/2009, 13h55
StephaneC.

Pour voir la taille du thread_cache :

Code:

SHOW VARIABLES LIKE 'thread_cache_size'

Ton erreur "Lost connection..." est sans doute due aux paramètres skip-networking ou bind-address.

Stéphane
24/03/2009, 14h27
Baud10

+-------------------+-------+
| Variable_name | Value |
+-------------------+-------+
| thread_cache_size | 0 |
+-------------------+-------+
1 row in set (0.00 sec)

my.cnf:
bind-address =127.0.0.1
skip-networking
(la directive est spéficiée mais vide)

ça m'inquiète d'autant plus que sur ma machine virtuelle j'me connecte en 1.6 ms, ce qui fait presque un facteur 10 !
24/03/2009, 15h57
Baud10

A noter que dans le log, c'est affiché:

Citation:

090316 22:07:09 [Warning] No argument was provided to --log-bin, and --log-bin-index was not used; so replication may break when this MySQL server acts as a master and has his hostname changed!! Please use '--log-bin=/var/run/mysqld/mysqld-bin' to avoid this problem.
090316 22:07:10 [Note] /usr/sbin/mysqld: ready for connections.
Version: '5.0.44-log' socket: '/var/run/mysqld/mysqld.sock' port: 0 Gentoo Linux mysql-5.0.44-r2

=> la socket écoute le port zéro ! (ce qui est confirmé dans SHOW VARIABLES LIKE 'PORT') mais mon my.cnf lui indique 3306.

D'ailleurs, j'arrive a me connecter en utilisant 'localhost' OU 'localhost:3306' OU 'localhost:0'...
25/03/2009, 14h13
StephaneC.

Oula oula oula ! On va voir les choses les unes après les autres, sinon on ne va jamais s'en sortir !!

Pour la variable thread_cache_size, tu peux essayer de l'augmenter et voir ce que ça donne.
Pour ça, tu peux essayer de voir la variation de la variable de statut threads_connected :

Code:

mysqladmin -u... extended-status -i10 | grep -i threads_connected

et tu mets la valeur de la variation dans thread_cache_size.

Si tu vois des différences, on passe à la suite :mrgreen:

Stéphane

Code:

1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
103
104
105
106
107
108
109
110
111
112
113
114
115
116
117
118
119
120
121
122
123
124
125
126
127
128
129
130
131
132
133
134
135
136
137
138
139
140
141
142
143
144
145
146
147
148
149
150
151
152
153
154
155
156
157
158
159
160
161
162
163
164
165
166
167
168
169
170
171
172
173
174
175
176
177
178
179
180
181
182
183
184
185
186
187
188
189
190
191
192
193
194
195
196
197
198
199
200
201
202
203
204
205
206
207
208
209
210
211
212
213
214
215
216
217
218
219
220
221
222
223
224
225
226
227
228
229
230
231
232
233
234
235
236
237
238
239
240
241
242
243
244
245
246
247
248
249
250
251
252
+-----------------------------------+----------+
| Variable_name                     | Value    |
+-----------------------------------+----------+
| Aborted_clients                   | 6        |
| Aborted_connects                  | 2        |
| Binlog_cache_disk_use             | 0        |
| Binlog_cache_use                  | 0        |
| Bytes_received                    | 3059874  |
| Bytes_sent                        | 33676711 |
| Com_admin_commands                | 0        |
| Com_alter_db                      | 0        |
| Com_alter_table                   | 9        |
| Com_analyze                       | 0        |
| Com_backup_table                  | 0        |
| Com_begin                         | 0        |
| Com_call_procedure                | 0        |
| Com_change_db                     | 3875     |
| Com_change_master                 | 0        |
| Com_check                         | 0        |
| Com_checksum                      | 0        |
| Com_commit                        | 1        |
| Com_create_db                     | 0        |
| Com_create_function               | 0        |
| Com_create_index                  | 0        |
| Com_create_table                  | 6        |
| Com_create_user                   | 0        |
| Com_dealloc_sql                   | 0        |
| Com_delete                        | 187      |
| Com_delete_multi                  | 0        |
| Com_do                            | 0        |
| Com_drop_db                       | 0        |
| Com_drop_function                 | 0        |
| Com_drop_index                    | 0        |
| Com_drop_table                    | 1        |
| Com_drop_user                     | 0        |
| Com_execute_sql                   | 0        |
| Com_flush                         | 0        |
| Com_grant                         | 0        |
| Com_ha_close                      | 0        |
| Com_ha_open                       | 0        |
| Com_ha_read                       | 0        |
| Com_help                          | 0        |
| Com_insert                        | 262      |
| Com_insert_select                 | 0        |
| Com_kill                          | 0        |
| Com_load                          | 0        |
| Com_load_master_data              | 0        |
| Com_load_master_table             | 0        |
| Com_lock_tables                   | 16       |
| Com_optimize                      | 0        |
| Com_preload_keys                  | 0        |
| Com_prepare_sql                   | 0        |
| Com_purge                         | 0        |
| Com_purge_before_date             | 0        |
| Com_rename_table                  | 0        |
| Com_repair                        | 0        |
| Com_replace                       | 0        |
| Com_replace_select                | 0        |
| Com_reset                         | 0        |
| Com_restore_table                 | 0        |
| Com_revoke                        | 0        |
| Com_revoke_all                    | 0        |
| Com_rollback                      | 0        |
| Com_savepoint                     | 0        |
| Com_select                        | 16540    |
| Com_set_option                    | 6160     |
| Com_show_binlog_events            | 0        |
| Com_show_binlogs                  | 31       |
| Com_show_charsets                 | 874      |
| Com_show_collations               | 874      |
| Com_show_column_types             | 0        |
| Com_show_create_db                | 8        |
| Com_show_create_table             | 1037     |
| Com_show_databases                | 876      |
| Com_show_errors                   | 0        |
| Com_show_fields                   | 1537     |
| Com_show_grants                   | 304      |
| Com_show_innodb_status            | 0        |
| Com_show_keys                     | 469      |
| Com_show_logs                     | 0        |
| Com_show_master_status            | 0        |
| Com_show_ndb_status               | 0        |
| Com_show_new_master               | 0        |
| Com_show_open_tables              | 0        |
| Com_show_privileges               | 0        |
| Com_show_processlist              | 1        |
| Com_show_slave_hosts              | 0        |
| Com_show_slave_status             | 0        |
| Com_show_status                   | 476      |
| Com_show_storage_engines          | 9        |
| Com_show_tables                   | 1046     |
| Com_show_triggers                 | 306      |
| Com_show_variables                | 3085     |
| Com_show_warnings                 | 147      |
| Com_slave_start                   | 0        |
| Com_slave_stop                    | 0        |
| Com_stmt_close                    | 0        |
| Com_stmt_execute                  | 0        |
| Com_stmt_fetch                    | 0        |
| Com_stmt_prepare                  | 0        |
| Com_stmt_reset                    | 0        |
| Com_stmt_send_long_data           | 0        |
| Com_truncate                      | 0        |
| Com_unlock_tables                 | 16       |
| Com_update                        | 900      |
| Com_update_multi                  | 0        |
| Com_xa_commit                     | 0        |
| Com_xa_end                        | 0        |
| Com_xa_prepare                    | 0        |
| Com_xa_recover                    | 0        |
| Com_xa_rollback                   | 0        |
| Com_xa_start                      | 0        |
| Compression                       | OFF      |
| Connections                       | 3022     |
| Created_tmp_disk_tables           | 2163     |
| Created_tmp_files                 | 0        |
| Created_tmp_tables                | 14911    |
| Delayed_errors                    | 0        |
| Delayed_insert_threads            | 0        |
| Delayed_writes                    | 0        |
| Flush_commands                    | 1        |
| Handler_commit                    | 0        |
| Handler_delete                    | 126      |
| Handler_discover                  | 0        |
| Handler_prepare                   | 0        |
| Handler_read_first                | 5620     |
| Handler_read_key                  | 9604     |
| Handler_read_next                 | 2370     |
| Handler_read_prev                 | 0        |
| Handler_read_rnd                  | 1822     |
| Handler_read_rnd_next             | 406208   |
| Handler_rollback                  | 0        |
| Handler_savepoint                 | 0        |
| Handler_savepoint_rollback        | 0        |
| Handler_update                    | 869      |
| Handler_write                     | 303379   |
| Innodb_buffer_pool_pages_data     | 0        |
| Innodb_buffer_pool_pages_dirty    | 0        |
| Innodb_buffer_pool_pages_flushed  | 0        |
| Innodb_buffer_pool_pages_free     | 0        |
| Innodb_buffer_pool_pages_latched  | 0        |
| Innodb_buffer_pool_pages_misc     | 0        |
| Innodb_buffer_pool_pages_total    | 0        |
| Innodb_buffer_pool_read_ahead_rnd | 0        |
| Innodb_buffer_pool_read_ahead_seq | 0        |
| Innodb_buffer_pool_read_requests  | 0        |
| Innodb_buffer_pool_reads          | 0        |
| Innodb_buffer_pool_wait_free      | 0        |
| Innodb_buffer_pool_write_requests | 0        |
| Innodb_data_fsyncs                | 0        |
| Innodb_data_pending_fsyncs        | 0        |
| Innodb_data_pending_reads         | 0        |
| Innodb_data_pending_writes        | 0        |
| Innodb_data_read                  | 0        |
| Innodb_data_reads                 | 0        |
| Innodb_data_writes                | 0        |
| Innodb_data_written               | 0        |
| Innodb_dblwr_pages_written        | 0        |
| Innodb_dblwr_writes               | 0        |
| Innodb_log_waits                  | 0        |
| Innodb_log_write_requests         | 0        |
| Innodb_log_writes                 | 0        |
| Innodb_os_log_fsyncs              | 0        |
| Innodb_os_log_pending_fsyncs      | 0        |
| Innodb_os_log_pending_writes      | 0        |
| Innodb_os_log_written             | 0        |
| Innodb_page_size                  | 0        |
| Innodb_pages_created              | 0        |
| Innodb_pages_read                 | 0        |
| Innodb_pages_written              | 0        |
| Innodb_row_lock_current_waits     | 0        |
| Innodb_row_lock_time              | 0        |
| Innodb_row_lock_time_avg          | 0        |
| Innodb_row_lock_time_max          | 0        |
| Innodb_row_lock_waits             | 0        |
| Innodb_rows_deleted               | 0        |
| Innodb_rows_inserted              | 0        |
| Innodb_rows_read                  | 0        |
| Innodb_rows_updated               | 0        |
| Key_blocks_not_flushed            | 0        |
| Key_blocks_unused                 | 14473    |
| Key_blocks_used                   | 25       |
| Key_read_requests                 | 14093    |
| Key_reads                         | 37       |
| Key_write_requests                | 684      |
| Key_writes                        | 337      |
| Last_query_cost                   | 0.000000 |
| Max_used_connections              | 2        |
| Not_flushed_delayed_rows          | 0        |
| Open_files                        | 120      |
| Open_streams                      | 0        |
| Open_tables                       | 64       |
| Opened_tables                     | 425      |
| Prepared_stmt_count               | 0        |
| Qcache_free_blocks                | 0        |
| Qcache_free_memory                | 0        |
| Qcache_hits                       | 0        |
| Qcache_inserts                    | 0        |
| Qcache_lowmem_prunes              | 0        |
| Qcache_not_cached                 | 0        |
| Qcache_queries_in_cache           | 0        |
| Qcache_total_blocks               | 0        |
| Questions                         | 46118    |
| Rpl_status                        | NULL     |
| Select_full_join                  | 10       |
| Select_full_range_join            | 0        |
| Select_range                      | 208      |
| Select_range_check                | 0        |
| Select_scan                       | 17518    |
| Slave_open_temp_tables            | 0        |
| Slave_retried_transactions        | 0        |
| Slave_running                     | OFF      |
| Slow_launch_threads               | 0        |
| Slow_queries                      | 0        |
| Sort_merge_passes                 | 0        |
| Sort_range                        | 5        |
| Sort_rows                         | 3326     |
| Sort_scan                         | 652      |
| Ssl_accept_renegotiates           | 0        |
| Ssl_accepts                       | 0        |
| Ssl_callback_cache_hits           | 0        |
| Ssl_cipher                        |          |
| Ssl_cipher_list                   |          |
| Ssl_client_connects               | 0        |
| Ssl_connect_renegotiates          | 0        |
| Ssl_ctx_verify_depth              | 0        |
| Ssl_ctx_verify_mode               | 0        |
| Ssl_default_timeout               | 0        |
| Ssl_finished_accepts              | 0        |
| Ssl_finished_connects             | 0        |
| Ssl_session_cache_hits            | 0        |
| Ssl_session_cache_misses          | 0        |
| Ssl_session_cache_mode            | NONE     |
| Ssl_session_cache_overflows       | 0        |
| Ssl_session_cache_size            | 0        |
| Ssl_session_cache_timeouts        | 0        |
| Ssl_sessions_reused               | 0        |
| Ssl_used_session_cache_entries    | 0        |
| Ssl_verify_depth                  | 0        |
| Ssl_verify_mode                   | 0        |
| Ssl_version                       |          |
| Table_locks_immediate             | 21463    |
| Table_locks_waited                | 1        |
| Tc_log_max_pages_used             | 0        |
| Tc_log_page_size                  | 0        |
| Tc_log_page_waits                 | 0        |
| Threads_cached                    | 1        |
| Threads_connected                 | 1        |
| Threads_created                   | 2963     |
| Threads_running                   | 1        |
| Uptime                            | 1205160  |
+-----------------------------------+----------+

Quand j'avais :
- thread_cache_size à 0, à chaque connexion la valeur "threads_created" incrémentait de 1 (durée: 14 ms)
- thread_cache_size à 1 ou 5, à chaque connexion la valeur "threads_created" ne s'incrémente plus (durée: 14 ms toujours)

A ce propos, comment faire pour que le "set global" perdure après un /etc/init.d/mysql restart ?

31/03/2009, 14h21
StephaneC.

Tu as bien regardé la variable threads_created à intervalles réguliers ?
Et tu es bien sur un environnement où il y a du trafic ?
Parce que mettre le thread_cache_size à 0 ou à 5, ça ne doit pas changer grand-chose sur les perfs de ton serveur...

Si tu veux que ton set global perdure, il faut que tu mettes ton option dans le my.cnf.

Stéphane
31/03/2009, 18h20
Baud10

Oui j'ai mis l'option -i1 actualisation toutes les secondes...

et le nombre de thread créé augmente à chaque rafraichissement de la page.

et quand je fais un set global thread_cache_size = 5;
ou même = 0, la valeur thread_created ne bouge plus. Remettre thread_cache_size à 0 et le thread_created reprend l'incrémentation habituelle.

Je suis sur une machine dédié (LAMP & TS) qui n'est pas du tout utilisé (à noter que le disque dur est en SAN ISCSI), donc aucun souci de traffic/CPU/mémoire...

et dans mon my.cnf il n'y a pas de directive thread_cache_size, il suffit donc de l'ajouter ? Mais bon comme la durée de connexion reste inchangée, ça sert à rien.

Mais ce délais de connexion c'est plutot consommateur de CPU (attente active) ou c'est de l'iowait (attente IRQ) ?
02/04/2009, 14h35
StephaneC.

Mea culpa, je n'avais pas compris que tu étais sur une machine sans trafic :?

Du coup, il n'y a aucun intérêt à modifier le thread_cache_size puisque ce paramètre permet de garder des threads en cache en cas de forts accès concurrents... donc tu peux laisser le paramètre à 0.

Eventuellement, il y a une autre piste (mais j'ai des doutes car tu travailles sur localhost) : quand tu te connectes à MySQL en utilisant des noms d'hôte plutôt que des adresses IP, MySQL fait une recherche DNS inverse qui peut prendre du temps.
Essaye peut-être d'ajouter la directive skip-name-resolve dans ton my.cnf.

Je n'ai pas d'autre idée pour l'instant :cry:

Stéphane
05/04/2009, 17h08
Baud10

Citation:

Envoyé par Baud10

Mes tests de comparaisons étaient tous basés sur le nom d'hôte du serveur MySQL => donc en socket. La différence ne devrait pas être donc du a ça. Néanmoins, j'avais essayé avec 127.0.0.1, mais la connexion échoue : "Lost connection to MySQL server at 'reading initial communication packet', system error: 111". En spéficiant ":/var/run/mysqld/mysqld.sock" ça fonctionne mais j'ai exactement le même temps.

Finalement tu m'as donné l'idée et j'ai testé.
J'ai mis le nom de la machine au lieu de localhost soit r*censuré*.ovh.net, et la connexion échoue, de la même façon que si je spéficie l'IP public ou 127.0.0.1.

Tant pis j'aurai essayé ! Merci de ton aide!