PHP+MySQL: тормозит добавление записи в таблицу / PHP, Perl, Python

ReSQL.ru

Мобильная версия Контакт Правила FAQ Помощь

Гость

Войти | Регистрация | Профиль | Очистить

Новые сообщения | Избранное

Форумы | Пользователи | Статистика | Мод. лог | Поиск

Доб. в избранное | Игнор. тему | Прикреп. тему | Пометить прочит. / непрочит. | Фильтр

Форумы / PHP, Perl, Python [игнор отключен] [закрыт для гостей] / PHP+MySQL: тормозит добавление записи в таблицу

25 сообщений из 81, страница 2 из 4

все

PHP+MySQL: тормозит добавление записи в таблицу

#39718377

gpbetatesting

Гость

vkleА можете пояснить попобробнее вот этот момент?gpbetatestingдобавление 7500 записей закончится только через неприличные 25 минутПри 50 запросов/сек это получается 150 секунд или 2,5 минуты на все вставки от начала и до окончания.
Вот прогнали Вы эти 7500 запросов за 2,5 минуты. А в таблице новые записи одна за другой неспешно стали появляться в последующие 22,5 минуты, или как?

Если вкратце, максимальная скорость добавления записей в таблицу около 5 в секунду. Соответственно я хоть 1000 запросов за секунду могу отправлять, они там как будто в очередь выстраиваются.

...

Рейтинг:

0 / 0

16.10.2018, 18:26

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718429

vkle

Участник

Откуда: Самара

Сообщения: 14 253

Рейтинг: 0 / 0

gpbetatesting,

По большому счету, без очереди не получится. Другое дело, как быстро она движется.

Вот смотрите, на таблице имеется уникальный ключ. При совпадении значения ключевого поля запрос должен изменить свое поведение с инсерта на апдейт.
Теперь ситуация. Таблица пустая. Пришли одновременно два запроса с одним и тем же значением ключевого поля. Каков ожидаемый результат, на Ваш взгляд? ;-)
Давайте по порядку. Две записи в таблице с одинаковым первичным ключом не имеют права существовать. В этой ситуации необходимо сделать один инсерт и один апдейт. Следовательно, какой-то из этих двух запросов станет первым, а какой-то вторым. Соответственно, второй запрос не может быть выполнен до тех пор, пока не закончится первый. Иначе запись не будет обновлена, а у Вас возникнут претензии к разработчикам СУБД. Это вкратце к вопросу о том, откуда в такой простейшей ситуации берутся очереди. Думаю, подробнее и глубже расскажут в профильном форуме.

Кроме того, используемая методика измерения времени может лажать. Ведь от запроса не требуется возвращать никаких данных, кроме успешно/ошибка. А когда у СУБД будет достаточно данных, для формирования такого ответа? Если коротко, то много раньше окончания выполнения запроса.

Может пригодится для размышления:

Когда у меня впервые возникла необходимость в целях оптимизации сделать временной профиль выполнения ХП (вызывалась запросом CALL() через PDO) с несколькими различной "тяжелости" запросами внутри и с несколькими возвращаемыми рекордсетами в контексте пхпшного скрипта, неожиданно оказалось, что что сам вызов (запрос CALL) в пхпшном скрипте проходит очень быстро (какие-то миллисекунды, вроде), но в некоторые моменты очередную возвращаемую порцию данных от "тяжелого" запроса пхп-скрипт ожидает подолгу, сотни миллисекунд. В этом смысле измеренные временные промежутки заставили меня задуматься от том, что именно я измеряю в тот или иной момент.

...

Рейтинг:

0 / 0

16.10.2018, 20:42

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718434

vkle

Участник

Откуда: Самара

Сообщения: 14 253

Рейтинг: 0 / 0

gpbetatesting,

Подумалось вот о чем. Конечно, если у Вас есть желание поэкспериментировать и разобраться.
Добавьте в Ваш скрипт после инсерт/апдейта ещё один запрос - селект со значениями потенциально измененяемых полей записи для этого первичного ключа и выводом значений. Кроме того, можно в самом начале и в самом конце запросить у СУБД ещё и текущее врамя для сравнения его с пхпшным.
Понимаю, что в таких опытах можно застрять по уши, но без детального анализа тут вряд ли можно получить готовый ответ о причинах ситуации.
Если интересно, есть мысль о паре более страшных тестов :-)))

...

Рейтинг:

0 / 0

16.10.2018, 20:55

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718435

miksoft

Участник

Сообщения: 36 746

Рейтинг: 0 / 0

gpbetatestingпараллельно дёргать функцию, показывающую число записей в таблицеА как именно это делаете?
Надеюсь, из консольного клиента mysql ?

...

Рейтинг:

0 / 0

16.10.2018, 20:56

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718443

gpbetatesting

Гость

vkle,

В нашем случае практически исключена ситуация одновременного поступления двух запросов с одинаковыми полями (сейчас структура таблицы такова, что есть два поля, которые сами по себе не уникальны, но их комбинации в строке - да).

miksoft,

да, через консоль. Сейчас опять отправил 100 запросов за полторы секунды и вызывал пару раз в секунду count(*) в консоли, картина такая:

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 8 |
+----------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 12 |
+----------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 15 |
+----------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 17 |
+----------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 18 |
+----------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 19 |
+----------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 20 |
+----------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 22 |
+----------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 22 |
+----------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 24 |
+----------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 25 |
+----------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 25 |
+----------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 26 |
+----------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 27 |
+----------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 28 |
+----------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 29 |
+----------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) from `users_table` WHERE `tb_gid`='LOADTEST1';
+----------+
| COUNT(*) |
+----------+
| 30 |
+----------+
1 row in set (0.00 sec)

...

Рейтинг:

0 / 0

16.10.2018, 21:17

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718446

vkle

Участник

Откуда: Самара

Сообщения: 14 253

Рейтинг: 0 / 0

gpbetatestingВ нашем случае практически исключена ситуация одновременного поступления двух запросов с одинаковыми полямиАга, только СУБД об этом не знает! )))) А если б и знала, то вряд ли поменяла алгоритм обработки запросов на желаемый Вами.
Хотя, если убрать уникальный (он же праймари) индекс и реализовать уникальность поля дополнительным запросом... Но не уверен, это пробовать надо.

...

Рейтинг:

0 / 0

16.10.2018, 21:27

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718447

miksoft

Участник

Сообщения: 36 746

Рейтинг: 0 / 0

gpbetatestingвызывал пару раз в секунду count(*) в консолиДобавьте в запрос получение текущего времени, чтобы интервалы времени были понятны.

...

Рейтинг:

0 / 0

16.10.2018, 21:28

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718450

gpbetatesting

Гость

miksoft,

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 11 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:00 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 16 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:02 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 18 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:03 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 21 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:05 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 25 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:06 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 29 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:07 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 32 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:09 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 36 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:10 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 38 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:11 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 40 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:12 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 42 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:13 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 44 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:14 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 45 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:15 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 48 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:16 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 51 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:17 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 53 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:18 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 54 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:19 |
+---------------------+
1 row in set (0.00 sec)

mysql> SELECT COUNT(*) FROM `users_table` WHERE `tb_gid`="LOADTEST1"; SELECT NOW();
+----------+
| COUNT(*) |
+----------+
| 57 |
+----------+
1 row in set (0.00 sec)

+---------------------+
| NOW() |
+---------------------+
| 2018-10-16 21:33:20 |
+---------------------+
1 row in set (0.00 sec)

...

Рейтинг:

0 / 0

16.10.2018, 21:34

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718453

miksoft

Участник

Сообщения: 36 746

Рейтинг: 0 / 0

gpbetatesting,

Дичь какая-то... так быть не должно.

Добавьте в скрипт, который вставляет записи, логирование в файл в моменты начала и конца скрипта с указанием времени (с долями секунд) и какого-нибудь идентификатора (PID или типа того, не знаю что есть в PHP), чтобы разные запуски скрипта друг от друга отличать.

...

Рейтинг:

0 / 0

16.10.2018, 21:39

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718457

gpbetatesting

Гость

miksoft,

Лог 39 запросов, отправленных за ~0.5c:

PID 29280, start time 1539716426.7522
PID 29280 connected at 1539716426.7525
PID 17201, start time 1539716435.044
PID 17201 connected at 1539716435.0444
PID 17201 query successfully ended at 1539716435.0452
PID 23306, start time 1539716435.2123
PID 23306 connected at 1539716435.2127
PID 23079, start time 1539716435.4447
PID 23079 connected at 1539716435.445
PID 1519, start time 1539716435.4854
PID 1519 connected at 1539716435.4858
PID 1519 query successfully ended at 1539716435.4864
PID 22242, start time 1539716435.6199
PID 22242 connected at 1539716435.6202
PID 1520, start time 1539716435.7779
PID 1520 connected at 1539716435.7782
PID 2154, start time 1539716435.9515
PID 2154 connected at 1539716435.9518
PID 21226, start time 1539716435.9527
PID 2154 query successfully ended at 1539716435.9528
PID 21226 connected at 1539716435.9529
PID 23307, start time 1539716436.0826
PID 23307 connected at 1539716436.083
PID 29280, start time 1539716436.2331
PID 29280 connected at 1539716436.2335
PID 29280 query successfully ended at 1539716436.234
PID 17201, start time 1539716436.3766
PID 17201 connected at 1539716436.3769
PID 23306, start time 1539716436.3911
PID 23306 connected at 1539716436.3915
PID 23079, start time 1539716436.5826
PID 23079 connected at 1539716436.5829
PID 23079 query successfully ended at 1539716436.5834
PID 1519, start time 1539716436.8674
PID 1519 connected at 1539716436.8678
PID 22242, start time 1539716436.981
PID 22242 connected at 1539716436.9814
PID 1520, start time 1539716437.17
PID 1520 connected at 1539716437.1703
PID 1520 query successfully ended at 1539716437.171
PID 2154, start time 1539716437.3192
PID 2154 connected at 1539716437.3196
PID 21226, start time 1539716437.4105
PID 21226 connected at 1539716437.411
PID 23307, start time 1539716437.56
PID 23307 connected at 1539716437.5604
PID 23307 query successfully ended at 1539716437.561
PID 29280, start time 1539716437.7036
PID 29280 connected at 1539716437.7041
PID 17201, start time 1539716437.7088
PID 17201 connected at 1539716437.7091
PID 23306, start time 1539716437.8488
PID 23306 connected at 1539716437.8491
PID 23306 query successfully ended at 1539716437.8498
PID 23079, start time 1539716437.9841
PID 23079 connected at 1539716437.9846
PID 1519, start time 1539716437.9924
PID 1519 connected at 1539716437.9927
PID 22242, start time 1539716438.1241
PID 22242 connected at 1539716438.1244
PID 22242 query successfully ended at 1539716438.125
PID 1520, start time 1539716438.2517
PID 1520 connected at 1539716438.252
PID 2154, start time 1539716438.2652
PID 2154 connected at 1539716438.2656
PID 21226, start time 1539716438.4812
PID 21226 connected at 1539716438.4817
PID 21226 query successfully ended at 1539716438.4821
PID 23307, start time 1539716438.5143
PID 23307 connected at 1539716438.5147
PID 29280, start time 1539716438.6191
PID 29280 connected at 1539716438.6195
PID 17201, start time 1539716438.7526
PID 17201 connected at 1539716438.753
PID 17201 query successfully ended at 1539716438.7538
PID 23306, start time 1539716438.8758
PID 23306 connected at 1539716438.8762
PID 23079, start time 1539716438.8909
PID 23079 connected at 1539716438.8913
PID 1519, start time 1539716439.0461
PID 1519 connected at 1539716439.0465
PID 1519 query successfully ended at 1539716439.0471
PID 22242, start time 1539716439.146
PID 22242 connected at 1539716439.1464
PID 1520, start time 1539716439.1939
PID 1520 connected at 1539716439.1943
PID 2154, start time 1539716439.334
PID 2154 connected at 1539716439.3344
PID 2154 query successfully ended at 1539716439.335
PID 21226, start time 1539716439.445
PID 21226 connected at 1539716439.4454
PID 23307, start time 1539716439.4789
PID 23307 connected at 1539716439.4793
PID 29280, start time 1539716439.6265
PID 29280 connected at 1539716439.6269
PID 29280 query successfully ended at 1539716439.6276
PID 17201, start time 1539716439.7121
PID 17201 connected at 1539716439.7126
PID 23306, start time 1539716439.7637
PID 23306 connected at 1539716439.7641
PID 23079, start time 1539716440.0621
PID 23079 connected at 1539716440.0625
PID 23079 query successfully ended at 1539716440.0631
PID 1519, start time 1539716440.2111
PID 1519 connected at 1539716440.2115
PID 22242, start time 1539716440.3511
PID 22242 connected at 1539716440.3515
PID 1520, start time 1539716440.5052
PID 1520 connected at 1539716440.5056
PID 1520 query successfully ended at 1539716440.5061
PID 2154, start time 1539716440.6368
PID 2154 connected at 1539716440.6372
PID 21226, start time 1539716440.646
PID 21226 connected at 1539716440.6464
PID 23307, start time 1539716440.7957
PID 23307 connected at 1539716440.796
PID 23307 query successfully ended at 1539716440.7967
PID 29280, start time 1539716440.9049
PID 29280 connected at 1539716440.9052
PID 17201, start time 1539716440.9385
PID 17201 connected at 1539716440.9388
PID 23306, start time 1539716441.1038
PID 23306 connected at 1539716441.1041
PID 23306 query successfully ended at 1539716441.1047
PID 23079, start time 1539716441.2346
PID 23079 connected at 1539716441.235
PID 1519, start time 1539716441.2476
PID 1519 connected at 1539716441.2479
PID 22242, start time 1539716441.4
PID 22242 connected at 1539716441.4004
PID 22242 query successfully ended at 1539716441.401
PID 1520, start time 1539716441.566
PID 1520 connected at 1539716441.5664
PID 2154, start time 1539716441.6172
PID 2154 connected at 1539716441.6175
PID 21226, start time 1539716441.7431
PID 21226 connected at 1539716441.7435
PID 21226 query successfully ended at 1539716441.7441
PID 29280, start time 1539716441.874
PID 23307, start time 1539716441.874
PID 29280 connected at 1539716441.8743
PID 23307 connected at 1539716441.8743
PID 17201, start time 1539716442.0127
PID 17201 connected at 1539716442.0131
PID 17201 query successfully ended at 1539716442.0137
PID 23306, start time 1539716442.1364
PID 23306 connected at 1539716442.1368
PID 23079, start time 1539716442.1512
PID 23079 connected at 1539716442.1515
PID 1519, start time 1539716442.3884
PID 1519 connected at 1539716442.3888
PID 1519 query successfully ended at 1539716442.3894
PID 22242, start time 1539716442.3931
PID 22242 connected at 1539716442.3934
PID 1520, start time 1539716442.5177
PID 1520 connected at 1539716442.518
PID 2154, start time 1539716442.646
PID 2154 connected at 1539716442.6465
PID 2154 query successfully ended at 1539716442.647
PID 21226, start time 1539716442.7659
PID 21226 connected at 1539716442.7663
PID 29280, start time 1539716442.7808
PID 29280 connected at 1539716442.7812
PID 23307, start time 1539716442.9044
PID 23307 connected at 1539716442.9047
PID 23307 query successfully ended at 1539716442.9052
PID 17201, start time 1539716443.0343
PID 17201 connected at 1539716443.0346
PID 23306, start time 1539716443.0494
PID 23306 connected at 1539716443.0498
PID 23079, start time 1539716443.1885
PID 23079 connected at 1539716443.1889
PID 23079 query successfully ended at 1539716443.1895
PID 1519, start time 1539716443.3081
PID 1519 connected at 1539716443.3084
PID 22242, start time 1539716443.3232
PID 22242 connected at 1539716443.3236
PID 1520, start time 1539716443.4575
PID 1520 connected at 1539716443.4578
PID 1520 query successfully ended at 1539716443.4584
PID 2154, start time 1539716443.5977
PID 2154 connected at 1539716443.5981
PID 21226, start time 1539716443.6714
PID 21226 connected at 1539716443.6718
PID 29280, start time 1539716443.8861
PID 29280 connected at 1539716443.8865
PID 29280 query successfully ended at 1539716443.8871
PID 23307, start time 1539716443.8989
PID 23307 connected at 1539716443.8992
PID 17201, start time 1539716444.0219
PID 17201 connected at 1539716444.0222
PID 23306, start time 1539716444.1668
PID 23306 connected at 1539716444.1672
PID 23306 query successfully ended at 1539716444.1679
PID 23079, start time 1539716444.2599
PID 23079 connected at 1539716444.2602
PID 1519, start time 1539716444.31
PID 1519 connected at 1539716444.3104
PID 22242, start time 1539716444.4414
PID 22242 connected at 1539716444.4418
PID 22242 query successfully ended at 1539716444.4424
PID 1520, start time 1539716444.9212
PID 1520 connected at 1539716444.9216
PID 2154, start time 1539716445.3391
PID 2154 connected at 1539716445.3395
PID 2154 query successfully ended at 1539716445.3409
PID 21226, start time 1539716445.4828
PID 21226 connected at 1539716445.4831
PID 29280, start time 1539716445.6461
PID 29280 connected at 1539716445.6466
PID 23307, start time 1539716446.0106
PID 23307 connected at 1539716446.011
PID 23307 query successfully ended at 1539716446.0116
PID 17201, start time 1539716446.1476
PID 17201 connected at 1539716446.1479
PID 23306, start time 1539716446.657
PID 23306 connected at 1539716446.6575
PID 23079, start time 1539716446.6712
PID 23079 connected at 1539716446.6715
PID 23079 query successfully ended at 1539716446.6721
PID 1519, start time 1539716446.8096
PID 1519 connected at 1539716446.81
PID 22242, start time 1539716446.9308
PID 22242 connected at 1539716446.9312
PID 1520, start time 1539716447.1816
PID 1520 connected at 1539716447.182
PID 1520 query successfully ended at 1539716447.1826
PID 2154, start time 1539716447.2011
PID 2154 connected at 1539716447.2015
PID 21226, start time 1539716447.3252
PID 21226 connected at 1539716447.3256
PID 29280, start time 1539716447.3338
PID 29280 connected at 1539716447.3342
PID 23307, start time 1539716447.5683
PID 23307 connected at 1539716447.5686
PID 23307 query successfully ended at 1539716447.5692
PID 17201, start time 1539716447.7054
PID 17201 connected at 1539716447.7058
PID 23306, start time 1539716447.8413
PID 23306 connected at 1539716447.8417
PID 23079, start time 1539716448.2261
PID 23079 connected at 1539716448.2265
PID 23079 query successfully ended at 1539716448.227
PID 1519, start time 1539716448.3593
PID 1519 connected at 1539716448.3596
PID 22242, start time 1539716448.4961
PID 22242 connected at 1539716448.4965
PID 1520, start time 1539716448.8571
PID 1520 connected at 1539716448.8575
PID 1520 query successfully ended at 1539716448.858
PID 2154, start time 1539716448.9431
PID 2154 connected at 1539716448.9434
PID 21226, start time 1539716448.9929
PID 21226 connected at 1539716448.9934
PID 29280, start time 1539716449.1301
PID 29280 connected at 1539716449.1305
PID 29280 query successfully ended at 1539716449.131
PID 23307, start time 1539716449.2635
PID 23307 connected at 1539716449.2639
PID 17201, start time 1539716449.3154
PID 17201 connected at 1539716449.3157
PID 23306, start time 1539716449.5901
PID 23306 connected at 1539716449.5905
PID 23306 query successfully ended at 1539716449.5911
PID 23079, start time 1539716449.7222
PID 23079 connected at 1539716449.7225
PID 1519, start time 1539716449.7743
PID 1519 connected at 1539716449.7747
PID 22242, start time 1539716449.9226
PID 22242 connected at 1539716449.923
PID 22242 query successfully ended at 1539716449.9235
PID 1520, start time 1539716450.0114
PID 1520 connected at 1539716450.0117
PID 2154, start time 1539716450.0718
PID 2154 connected at 1539716450.0722
PID 29280, start time 1539716461.9396
PID 29280 connected at 1539716461.9399
PID 23307, start time 1539716462.077
PID 23307 connected at 1539716462.0774

start time - начало скрипта, connected - сразу после подключения, query successfully ended - после $stmt->execute(). Что интересно, последний пункт записался далеко не у всех PID, однако, все записи добавились в таблицу без потерь.

...

Рейтинг:

0 / 0

16.10.2018, 22:06

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718461

miksoft

Участник

Сообщения: 36 746

Рейтинг: 0 / 0

gpbetatesting,

А чего так неравномерно start time идут?
Интервал колеблется от 0 до почти 12 секунд.

...

Рейтинг:

0 / 0

16.10.2018, 22:20

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718464

gpbetatesting

Гость

miksoft,

А вот этого я сразу и не заметил. Моя "атака" это отправка запросов через равный промежуток (доли секунды), т.е. между ними очень мало времени. Вот чуть более ровный лог, но всё равно сильный отрыв в конце:

PID 23079, start time 1539718037.4256
PID 23079 connected at 1539718037.4259
PID 23307, start time 1539718037.5401
PID 23307 connected at 1539718037.5406
PID 29280, start time 1539718037.5478
PID 29280 connected at 1539718037.5482
PID 29280 query successfully ended at 1539718037.549
PID 1520, start time 1539718037.5639
PID 1520 connected at 1539718037.5642
PID 1520 query successfully ended at 1539718037.5648
PID 21226, start time 1539718037.5819
PID 21226 connected at 1539718037.5822
PID 21226 query successfully ended at 1539718037.5828
PID 22242, start time 1539718037.606
PID 22242 connected at 1539718037.6064
PID 22242 query successfully ended at 1539718037.6069
PID 1519, start time 1539718037.6109
PID 1519 connected at 1539718037.6111
PID 23306, start time 1539718037.6115
PID 23306 connected at 1539718037.6118
PID 2154, start time 1539718037.653
PID 2154 connected at 1539718037.6534
PID 2154 query successfully ended at 1539718037.6539
PID 17201, start time 1539718037.6592
PID 17201 connected at 1539718037.6596
PID 17201 query successfully ended at 1539718037.66
PID 23079, start time 1539718037.7174
PID 23079 connected at 1539718037.7178
PID 23307, start time 1539718037.7332
PID 23307 connected at 1539718037.7335
PID 23307 query successfully ended at 1539718037.734
PID 29280, start time 1539718037.7547
PID 29280 connected at 1539718037.7551
PID 29280 query successfully ended at 1539718037.7557
PID 1520, start time 1539718037.7614
PID 21226, start time 1539718037.7616
PID 1520 connected at 1539718037.7618
PID 21226 connected at 1539718037.7618
PID 22242, start time 1539718037.7623
PID 21226 query successfully ended at 1539718037.7626
PID 22242 connected at 1539718037.763
PID 22242 query successfully ended at 1539718037.7635
PID 1519, start time 1539718037.7669
PID 1519 connected at 1539718037.7672
PID 2154, start time 1539718037.7955
PID 23306, start time 1539718037.7955
PID 2154 connected at 1539718037.7959
PID 23306 connected at 1539718037.7959
PID 17201, start time 1539718037.8971
PID 17201 connected at 1539718037.8975
PID 23079, start time 1539718037.9335
PID 23307, start time 1539718037.9337
PID 23079 connected at 1539718037.9338
PID 23307 connected at 1539718037.9339
PID 29280, start time 1539718037.9342
PID 1520, start time 1539718037.9345
PID 23079 query successfully ended at 1539718037.9345
PID 1520 connected at 1539718037.9348
PID 29280 connected at 1539718037.935
PID 21226, start time 1539718037.9355
PID 22242, start time 1539718037.9384
PID 1519, start time 1539718037.9384
PID 29280 query successfully ended at 1539718037.9386
PID 22242 connected at 1539718037.9387
PID 1519 connected at 1539718037.9387
PID 21226 connected at 1539718037.9389
PID 22242 query successfully ended at 1539718037.9391
PID 21226 query successfully ended at 1539718037.9393
PID 23306, start time 1539718037.9396
PID 23306 connected at 1539718037.9398
PID 23306 query successfully ended at 1539718037.9401
PID 2154, start time 1539718038.072
PID 2154 connected at 1539718038.0725
PID 17201, start time 1539718038.0755
PID 17201 connected at 1539718038.0762
PID 23079, start time 1539718038.1031
PID 23079 connected at 1539718038.1035
PID 23079 query successfully ended at 1539718038.1041
PID 23307, start time 1539718038.1128
PID 23307 connected at 1539718038.1131
PID 29280, start time 1539718038.1154
PID 22242, start time 1539718038.1155
PID 22242 connected at 1539718038.1158
PID 22242 query successfully ended at 1539718038.1164
PID 1520, start time 1539718038.1165
PID 21226, start time 1539718038.1166
PID 29280 connected at 1539718038.1168
PID 21226 connected at 1539718038.1169
PID 1520 connected at 1539718038.118
PID 1520 query successfully ended at 1539718038.1185
PID 1519, start time 1539718038.2831
PID 1519 connected at 1539718038.2835
PID 1519 query successfully ended at 1539718038.2841
PID 2154, start time 1539718038.2854
PID 23306, start time 1539718038.2854
PID 17201, start time 1539718038.2856
PID 2154 connected at 1539718038.2857
PID 23306 connected at 1539718038.2858
PID 17201 connected at 1539718038.2858
PID 23306 query successfully ended at 1539718038.2864
PID 23079, start time 1539718038.2878
PID 23079 connected at 1539718038.288
PID 23079 query successfully ended at 1539718038.2884
PID 23307, start time 1539718038.2892
PID 22242, start time 1539718038.2902
PID 23307 connected at 1539718038.2904
PID 22242 connected at 1539718038.2936
PID 22242 query successfully ended at 1539718038.2941
PID 29280, start time 1539718038.4281
PID 29280 connected at 1539718038.4285
PID 21226, start time 1539718038.4582
PID 1520, start time 1539718038.4582
PID 1519, start time 1539718038.4584
PID 23306, start time 1539718038.4586
PID 1520 connected at 1539718038.4586
PID 23306 connected at 1539718038.4588
PID 2154, start time 1539718038.4591
PID 21226 connected at 1539718038.4594
PID 1519 connected at 1539718038.4599
PID 21226 query successfully ended at 1539718038.46
PID 2154 connected at 1539718038.4603
PID 17201, start time 1539718038.4603
PID 1519 query successfully ended at 1539718038.4604
PID 17201 connected at 1539718038.4605
PID 2154 query successfully ended at 1539718038.4609
PID 23307, start time 1539718038.464
PID 22242, start time 1539718038.4642
PID 23307 connected at 1539718038.4642
PID 22242 connected at 1539718038.4645
PID 23079, start time 1539718038.4646
PID 23307 query successfully ended at 1539718038.4647
PID 23079 connected at 1539718038.4648
PID 29280, start time 1539718038.4651
PID 23079 query successfully ended at 1539718038.4652
PID 29280 connected at 1539718038.4656
PID 23306, start time 1539718038.6381
PID 1520, start time 1539718038.6382
PID 1520 connected at 1539718038.6385
PID 23306 connected at 1539718038.6385
PID 1519, start time 1539718038.6389
PID 21226, start time 1539718038.639
PID 23306 query successfully ended at 1539718038.6391
PID 21226 connected at 1539718038.6393
PID 1519 connected at 1539718038.6396
PID 1519 query successfully ended at 1539718038.64
PID 2154, start time 1539718038.6401
PID 2154 connected at 1539718038.6404
PID 17201, start time 1539718038.6424
PID 17201 connected at 1539718038.6426
PID 23307, start time 1539718038.6429
PID 17201 query successfully ended at 1539718038.643
PID 23307 connected at 1539718038.6433
PID 22242, start time 1539718038.6465
PID 22242 connected at 1539718038.6486
PID 22242 query successfully ended at 1539718038.649
PID 23079, start time 1539718038.6522
PID 23079 connected at 1539718038.6524
PID 29280, start time 1539718038.6524
PID 29280 connected at 1539718038.6527
PID 29280 query successfully ended at 1539718038.6531
PID 23306, start time 1539718038.8162
PID 23306 connected at 1539718038.8165
PID 21226, start time 1539718038.8167
PID 1519, start time 1539718038.8168
PID 21226 connected at 1539718038.8169
PID 23306 query successfully ended at 1539718038.8171
PID 1520, start time 1539718038.8175
PID 1519 connected at 1539718038.8176
PID 2154, start time 1539718038.8179
PID 2154 connected at 1539718038.8181
PID 17201, start time 1539718038.8186
PID 17201 connected at 1539718038.8189
PID 1520 connected at 1539718038.819
PID 23306, start time 1539718038.8191
PID 23306 connected at 1539718038.8193
PID 1520 query successfully ended at 1539718038.8195
PID 17201 query successfully ended at 1539718038.8196
PID 23306 query successfully ended at 1539718038.8199
PID 22242, start time 1539718038.8217
PID 22242 connected at 1539718038.8219
PID 29280, start time 1539718038.9842
PID 21226, start time 1539718038.9845
PID 23079, start time 1539718038.9845
PID 29280 connected at 1539718038.9845
PID 23079 connected at 1539718038.9847
PID 29280 query successfully ended at 1539718038.9852
PID 21226 connected at 1539718038.9855
PID 23307, start time 1539718038.9856
PID 1519, start time 1539718038.9856
PID 23307 connected at 1539718038.9858
PID 1519 connected at 1539718038.9859
PID 21226 query successfully ended at 1539718038.9861
PID 23307 query successfully ended at 1539718038.9864
PID 17201, start time 1539718039.1245
PID 17201 connected at 1539718039.1249
PID 1520, start time 1539718039.1491
PID 2154, start time 1539718039.1493
PID 1520 connected at 1539718039.1494
PID 2154 connected at 1539718039.1495
PID 1520 query successfully ended at 1539718039.1501
PID 23306, start time 1539718039.1666
PID 29280, start time 1539718039.1674
PID 22242, start time 1539718039.1675
PID 23079, start time 1539718039.1676
PID 22242 connected at 1539718039.1677
PID 23079 connected at 1539718039.1678
PID 23307, start time 1539718039.1683
PID 29280 connected at 1539718039.1688
PID 23306 connected at 1539718039.1689
PID 23307 connected at 1539718039.1692
PID 21226, start time 1539718039.1693
PID 23306 query successfully ended at 1539718039.1694
PID 21226 connected at 1539718039.1695
PID 23307 query successfully ended at 1539718039.1697
PID 21226 query successfully ended at 1539718039.1703
PID 1519, start time 1539718039.3282
PID 17201, start time 1539718039.3284
PID 1520, start time 1539718039.3284
PID 1519 connected at 1539718039.3286
PID 17201 connected at 1539718039.3286
PID 1520 connected at 1539718039.3286
PID 2154, start time 1539718039.3288
PID 2154 connected at 1539718039.329
PID 17201 query successfully ended at 1539718039.3294
PID 1519 query successfully ended at 1539718039.3294
PID 22242, start time 1539718039.3339
PID 22242 connected at 1539718039.3341
PID 22242 query successfully ended at 1539718039.3346
PID 23079, start time 1539718039.335
PID 29280, start time 1539718039.3355
PID 23306, start time 1539718039.3355
PID 23079 connected at 1539718039.3358
PID 23307, start time 1539718039.3358
PID 23307 connected at 1539718039.336
PID 21226, start time 1539718039.3365
PID 22242, start time 1539718039.3366
PID 21226 connected at 1539718039.3367
PID 23306 connected at 1539718039.3368
PID 29280 connected at 1539718039.337
PID 22242 connected at 1539718039.3372
PID 23306 query successfully ended at 1539718039.3374
PID 29280 query successfully ended at 1539718039.3375
PID 22242 query successfully ended at 1539718039.3377
PID 1520, start time 1539718039.4731
PID 1520 connected at 1539718039.4735
PID 17201, start time 1539718039.5156
PID 17201 connected at 1539718039.516
PID 17201 query successfully ended at 1539718039.5167
PID 2154, start time 1539718039.5173
PID 2154 connected at 1539718039.5176
PID 1519, start time 1539718039.5222
PID 1519 connected at 1539718039.5224
PID 1519 query successfully ended at 1539718039.523
PID 23079, start time 1539718039.5234
PID 23079 connected at 1539718039.5236
PID 23307, start time 1539718039.5236
PID 23307 connected at 1539718039.5238
PID 23079 query successfully ended at 1539718039.5239
PID 29280, start time 1539718039.6955
PID 29280 connected at 1539718039.6959
PID 23306, start time 1539718039.6963
PID 23306 connected at 1539718039.6965
PID 29280 query successfully ended at 1539718039.6966
PID 21226, start time 1539718039.703
PID 21226 connected at 1539718039.7032
PID 22242, start time 1539718039.7034
PID 22242 connected at 1539718039.7036
PID 1520, start time 1539718039.7037
PID 21226 query successfully ended at 1539718039.7038
PID 17201, start time 1539718039.7039
PID 1520 connected at 1539718039.7041
PID 21226, start time 1539718039.7049
PID 2154, start time 1539718039.705
PID 17201 connected at 1539718039.705
PID 21226 connected at 1539718039.7054
PID 2154 connected at 1539718039.7054
PID 17201 query successfully ended at 1539718039.7055
PID 2154 query successfully ended at 1539718039.7059
PID 23079, start time 1539718039.7131
PID 23079 connected at 1539718039.7133
PID 23307, start time 1539718039.8778
PID 23307 connected at 1539718039.8781
PID 29280, start time 1539718039.8785
PID 23307 query successfully ended at 1539718039.8787
PID 29280 connected at 1539718039.8787
PID 23306, start time 1539718039.8863
PID 23306 connected at 1539718039.8865
PID 1519, start time 1539718039.8866
PID 1519 connected at 1539718039.8868
PID 22242, start time 1539718039.8869
PID 23306 query successfully ended at 1539718039.8871
PID 22242 connected at 1539718039.8871
PID 1520, start time 1539718039.8879
PID 1520 connected at 1539718039.8887
PID 17201, start time 1539718039.8888
PID 21226, start time 1539718039.8888
PID 2154, start time 1539718039.8897
PID 21226 connected at 1539718039.8899
PID 23307, start time 1539718039.8901
PID 2154 connected at 1539718039.8901
PID 23079, start time 1539718039.8904
PID 21226 query successfully ended at 1539718039.8904
PID 23079 connected at 1539718039.8907
PID 23307 connected at 1539718039.8908
PID 17201 connected at 1539718039.8908
PID 23079 query successfully ended at 1539718039.8911
PID 23307 query successfully ended at 1539718039.8912
PID 17201 query successfully ended at 1539718039.8914
PID 29280, start time 1539718040.0275
PID 29280 connected at 1539718040.0279
PID 1519, start time 1539718040.0776
PID 23306, start time 1539718040.0777
PID 1520, start time 1539718040.0779
PID 1519 connected at 1539718040.0779
PID 23306 connected at 1539718040.078
PID 1519 query successfully ended at 1539718040.0787
PID 23306 query successfully ended at 1539718040.0788
PID 22242, start time 1539718040.079
PID 22242 connected at 1539718040.0792
PID 21226, start time 1539718040.0803
PID 21226 connected at 1539718040.0806
PID 1520 connected at 1539718040.0808
PID 21226 query successfully ended at 1539718040.0811
PID 2154, start time 1539718040.0823
PID 2154 connected at 1539718040.0825
PID 23079, start time 1539718040.237
PID 17201, start time 1539718040.2372
PID 17201 connected at 1539718040.2374
PID 23307, start time 1539718040.2375
PID 23307 connected at 1539718040.2377
PID 17201 query successfully ended at 1539718040.2381
PID 23306, start time 1539718040.2381
PID 29280, start time 1539718040.2382
PID 23079 connected at 1539718040.2383
PID 29280 connected at 1539718040.2384
PID 23306 connected at 1539718040.2388
PID 23079 query successfully ended at 1539718040.2388
PID 1519, start time 1539718040.2404
PID 1519 connected at 1539718040.2406
PID 1519 query successfully ended at 1539718040.2409
PID 22242, start time 1539718040.4185
PID 22242 connected at 1539718040.4188
PID 21226, start time 1539718040.4189
PID 1520, start time 1539718040.4196
PID 1520 connected at 1539718040.4199
PID 2154, start time 1539718040.4201
PID 17201, start time 1539718040.4202
PID 23079, start time 1539718040.4211
PID 23307, start time 1539718040.4212
PID 29280, start time 1539718040.4213
PID 17201 connected at 1539718040.4214
PID 23306, start time 1539718040.422
PID 22242 query successfully ended at 1539718040.422
PID 23307 connected at 1539718040.4225
PID 29280 connected at 1539718040.4227
PID 23306 connected at 1539718040.423
PID 23307 query successfully ended at 1539718040.423
PID 23079 connected at 1539718040.4233
PID 29280 query successfully ended at 1539718040.4233
PID 23306 query successfully ended at 1539718040.4234
PID 2154 connected at 1539718040.4238
PID 21226 connected at 1539718040.4238
PID 1519, start time 1539718040.4249
PID 1519 connected at 1539718040.4251
PID 1519 query successfully ended at 1539718040.4256
PID 17201, start time 1539718040.4261
PID 17201 connected at 1539718040.4264
PID 17201 query successfully ended at 1539718040.427
PID 22242, start time 1539718040.568
PID 22242 connected at 1539718040.5684
PID 1520, start time 1539718040.6277
PID 1520 connected at 1539718040.6281
PID 23307, start time 1539718040.6285
PID 23306, start time 1539718040.6287
PID 1520 query successfully ended at 1539718040.6287
PID 29280, start time 1539718040.6287
PID 2154, start time 1539718040.6299
PID 1519, start time 1539718040.63
PID 29280 connected at 1539718040.6301
PID 17201, start time 1539718040.6302
PID 21226, start time 1539718040.6302
PID 23079, start time 1539718040.631
PID 22242, start time 1539718040.631
PID 1520, start time 1539718040.6311
PID 17201 connected at 1539718040.6312
PID 23307 connected at 1539718040.6313
PID 1519 connected at 1539718040.6314
PID 29280, start time 1539718040.632
PID 22242 connected at 1539718040.6322
PID 23306 connected at 1539718040.6326
PID 23079 connected at 1539718040.6326
PID 21226 connected at 1539718040.6328
PID 2154 connected at 1539718040.6331
PID 29280 connected at 1539718040.6334
PID 1520 connected at 1539718040.6334
PID 23079 query successfully ended at 1539718040.6335
PID 23306 query successfully ended at 1539718040.6335
PID 21226 query successfully ended at 1539718040.6338
PID 29280 query successfully ended at 1539718040.6342
PID 2154 query successfully ended at 1539718040.6343
PID 17201, start time 1539718040.809
PID 17201 connected at 1539718040.8094
PID 17201 query successfully ended at 1539718040.8099
PID 23307, start time 1539718040.8107
PID 23079, start time 1539718040.8115
PID 1519, start time 1539718040.8115
PID 21226, start time 1539718040.8119
PID 21226 connected at 1539718040.8122
PID 23307 connected at 1539718040.8122
PID 22242, start time 1539718040.8125
PID 29280, start time 1539718040.8125
PID 29280 connected at 1539718040.8129
PID 1519 connected at 1539718040.813
PID 23306, start time 1539718040.8134
PID 1519 query successfully ended at 1539718040.814
PID 2154, start time 1539718040.8144
PID 2154 connected at 1539718040.8146
PID 23079 connected at 1539718040.8149
PID 22242 connected at 1539718040.815
PID 2154 query successfully ended at 1539718040.8151
PID 23079 query successfully ended at 1539718040.8153
PID 23306 connected at 1539718040.8154
PID 22242 query successfully ended at 1539718040.8155
PID 1520, start time 1539718040.9719
PID 17201, start time 1539718040.9719
PID 21226, start time 1539718040.9721
PID 1520 connected at 1539718040.9722
PID 17201 connected at 1539718040.9722
PID 17201 query successfully ended at 1539718040.9729
PID 1519, start time 1539718040.9734
PID 23307, start time 1539718040.9736
PID 21226 connected at 1539718040.9737
PID 1519 connected at 1539718040.9738
PID 23307 connected at 1539718040.9738
PID 1519 query successfully ended at 1539718040.9745
PID 29280, start time 1539718074.7701
PID 29280 connected at 1539718074.7705
PID 2154, start time 1539718075.6762
PID 2154 connected at 1539718075.6765
PID 23079, start time 1539718076.5378
PID 23079 connected at 1539718076.5381
PID 22242, start time 1539718077.4256
PID 22242 connected at 1539718077.4259
PID 17201, start time 1539718081.415
PID 17201 connected at 1539718081.4153
PID 1520, start time 1539718081.5527
PID 1520 connected at 1539718081.553

...

Рейтинг:

0 / 0

16.10.2018, 22:29

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718465

vkle

Участник

Откуда: Самара

Сообщения: 14 253

Рейтинг: 0 / 0

gpbetatesting,

PID - это какого процесса? Он повторяется неоднократно, а в приведенном в первом посту коде циклов не видать.

...

Рейтинг:

0 / 0

16.10.2018, 22:34

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718467

gpbetatesting

Гость

vkle,

Я имел в виду, что дёргаю url со скриптом в цикле

...

Рейтинг:

0 / 0

16.10.2018, 22:38

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718468

vkle

Участник

Откуда: Самара

Сообщения: 14 253

Рейтинг: 0 / 0

gpbetatesting,

Тут ещё и вебсервер в доле... Грязновато выходит. Отсутствие третьей строчки "query successfully ended" - не результат какого-то таймаута (1 - ожидание клиентом ответа от вебсервера и 2 - ожидание вебсервером ответа скрипта)?

...

Рейтинг:

0 / 0

16.10.2018, 22:44

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718471

miksoft

Участник

Сообщения: 36 746

Рейтинг: 0 / 0

gpbetatestingдёргаю url со скриптом в циклеА в логах web-сервера что откладывается?

...

Рейтинг:

0 / 0

16.10.2018, 22:47

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718472

gpbetatesting

Гость

vkle,

query successfully ended идёт сразу перед echo результата клиенту. max_execution time 30, по умолчанию.

...

Рейтинг:

0 / 0

16.10.2018, 22:48

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718473

gpbetatesting

Гость

При этом все вызовы получили положительный ответ (выдаваемый при успешном выполнении запроса) в консоли браузера.

...

Рейтинг:

0 / 0

16.10.2018, 22:50

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718475

vkle

Участник

Откуда: Самара

Сообщения: 14 253

Рейтинг: 0 / 0

gpbetatesting,

Ну и куда эти "query successfully ended" подевались, кто их сожрал, если ответы успешные? Тут что-то совсем не так.
Покажите скрипт дергалки и логи (access и error) вебсервера.
И значения таймаутов в дергалке и в конфиге вебсервера.

Или же, исключите урл-дергалку, вебсервер и браузер вовсе, сконцентрируйтесь только на PHP + MySQL.

Вдогонку, max_execution_time - это максимальное время _работы_ пхп-скрипта без учета времени ожидания от внешних ресурсов, сна и т.п. Здесь скрипт простенький, едвали его время работы перевалит за несколько микросекунд.

...

Рейтинг:

0 / 0

16.10.2018, 22:57

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718487

gpbetatesting

Гость

Сейчас выяснилось, что на скорость запроса влияло параллельное выполнение двух других (одна выбирает из той же таблицы один результат по uid и gid, другая выбирает 100 самых свежих по gid), но они выполнялись с приемлемой скоростью. Их отключение позволило увеличить скорость функции вставки до ~50/сек, но в рабочем режиме эти три функции вызываются от множества юзеров, причём сама вставка чуть реже чтения результатов (которая всегда вызывается в начале сессии).

...

Рейтинг:

0 / 0

16.10.2018, 23:28

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718491

vkle

Участник

Откуда: Самара

Сообщения: 14 253

Рейтинг: 0 / 0

gpbetatestingИх отключение позволило увеличить скорость функции вставки до ~50/секЭто с примерно 5/сек, в десять раз? И без них означенные 7500 записей уходят в таблицу за положенные 2,5 минуты?

...

Рейтинг:

0 / 0

16.10.2018, 23:44

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718494

gpbetatesting

Гость

vkle,

Сейчас загнал 1000 записей, дёргая урл 60 раз в секунду, средняя скорость добавления без тех двух функций порядка 15-20 в сек.

...

Рейтинг:

0 / 0

16.10.2018, 23:55

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718509

vkle

Участник

Откуда: Самара

Сообщения: 14 253

Рейтинг: 0 / 0

gpbetatesting,

Интересу ради запилил тест. Далеко не идеальный, конечно, но уж как есть.
Имеется файл с тестовыми данными, которые заносятся в таблицу поодиночке. Часть данных имеет дубликаты в ключевом поле, таких 10%.
Для вставки каждой записи инициируется отдельный процесс PHP. В этом есть некоторая аналогия с вебсервером. Весьма приблизительная, конечно, но вполне соответствующая режиму CGI, который в чистом виде нынче практически не используется применительно к PHP. Нагрузку на проц, конечно, дает ощутить, и даже немного больше, чем на реальном вебсервере :-)
В процессе записи периодически запрашивается текущее количество строк в таблице. Зная расположение дубликатов в исходном файле, легко оценить ожидаемое количество строк. Эти запросы должны происходить гипотетически через каждую секунду (вычисленное время), но из-за ограниченности ресурсов компа в реальности получаются реже.

В выводе скрипта main.php в самом начале текущие настройки, потом в три столбика идут номер итерации, кол-во записей, время от предыдущей итерации.

По результатам.
1. На далеко не новом домашнем компе (СУБД MariaDB, PHP 5.6) уже при предполагаемых 50 з/с проц заметно не справляется, хотя, при 10 з/с усе прекрасно. А вот при 120 вместо одной расчетной секунды пилит тест пять секунд, ну и отставание факта от плана в таблице то ещё... В общем, на значениях свыше 25 тест повален, похоже, по нехватке ресурсов.
2. На более-менее приличном сервере о 12 ядрах и много памяти (СУБД MySQL 5.6, PHP 7.1) на предполагаемые 200 запросов (в секунду) уходит около 1,3 сек и отставание фактического от планового кол-ва записей исчисляется единицами (видимо, работающие в фоне дочерние процессы к моменту измерения не успели выполнить запись), а вот при 400 з/с уже наблюдается весьма заметное (десятки записей) отставание и сказывается нехватка ресурсов.

Ещё раз подчеркну: тест весьма дубовый.
Конфиг в init.php

...

Рейтинг:

0 / 0

17.10.2018, 02:27

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718532

vkle

Участник

Откуда: Самара

Сообщения: 14 253

Рейтинг: 0 / 0

Продолжение теста.
Если процессы не плодить и все запросы выполнить синхронно в одном цикле. Тут хорошо видна зависимость от движка таблицы, но зависимость от ресурсов незначительна.

На сервере

Код: plaintext

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.

$ php one.php 
Version = 5.6.41-log
Engine = InnoDB
Index = , PRIMARY KEY `id` (`id`)
Per sec = 1000
0       1       0.001
1000    910     2.812
2000    1820    2.911
3000    2729    2.828
4000    3638    2.724
5000    4547    2.725
6000    5456    2.663
7000    6365    2.757
8000    7274    2.837
9000    8183    2.832
10000   9092    2.753

$ php one.php 
Version = 5.6.41-log
Engine = MyISAM
Index = , PRIMARY KEY `id` (`id`)
Per sec = 1000
0       1       0
1000    910     1.205
2000    1820    1.21
3000    2729    1.21
4000    3638    1.223
5000    4547    1.238
6000    5456    1.237
7000    6365    1.23
8000    7274    1.222
9000    8183    1.228
10000   9092    1.229

На домашнем

Код: plaintext

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.

$ php one.php 
Version = 10.1.35-MariaDB-alt1.M80P.1
Engine = InnoDB
Index = , PRIMARY KEY `id` (`id`)
Per sec = 1000
0       1       0.002
1000    910     3.076
2000    1820    3.987
3000    2729    3.049
4000    3638    3.042
5000    4547    3.074
6000    5456    3.048
7000    6365    3.041
8000    7274    3.088
9000    8183    4.243
10000   9092    3.083

$ php one.php 
Version = 10.1.35-MariaDB-alt1.M80P.1
Engine = MyISAM
Index = , PRIMARY KEY `id` (`id`)
Per sec = 1000
0       1       0
1000    910     1.441
2000    1820    1.479
3000    2729    1.463
4000    3638    1.446
5000    4547    1.475
6000    5456    1.463
7000    6365    1.469
8000    7274    1.463
9000    8183    1.48
10000   9092    1.463

...

Рейтинг:

0 / 0

17.10.2018, 07:39

| Ответить | Цитировать | Написать

PHP+MySQL: тормозит добавление записи в таблицу

#39718565

Hett

Участник

Откуда: Бийск, Новосибирск

Сообщения: 13 075

Рейтинг: 0 / 0

на сколько я помню в mysql по дефолтну весьма избыточный уровень изоляции транзакций включен (serializable)
что если переключить в другой, например read committed ?

Код: sql

set isolation level read committed

...

Рейтинг:

0 / 0

17.10.2018, 09:42

| Ответить | Цитировать | Написать

25 сообщений из 81, страница 2 из 4

все

Форумы / PHP, Perl, Python [игнор отключен] [закрыт для гостей] / PHP+MySQL: тормозит добавление записи в таблицу

Цитировать

Написать

Автор*:

Ввести пароль для входа

Тема*:

Сообщение

Данное сообщение тематическое

Сообщение содержит картинки или видео 18+

Автор:

ВНИМАНИЕ! На данном подфоруме действуют строгие правила. Удостоверьтесь, что ваше сообщение соответствует им!

Форум или тема закрыты для гостей. Необходима авторизация!

Загрузить последнюю сохраненную версию

Вложение:

Вставить как галерею

Максимальный размер вложений: 4,0 МБ, аудио/видео: 8,0 МБ. Картинки большего размера ужимаются, если возможно.

Введите код, изображенный на картинке. Если код нечитаемый, кликните картинку, чтобы загрузить другой вариант.

Отправляя сообщение, я выражаю свое согласие с правилами форума и принимаю пользовательское соглашение.

Читали тему (0):

Читали форум (0):

Пользователи онлайн (0):

start [/forum/topic.php?fid=23&msg=39718464&tid=1460104]:	0ms
get settings:	9ms
get forum list:	12ms
check forum access:	4ms
check topic access:	4ms
track hit:	55ms
get topic data:	9ms
get forum data:	2ms
get page messages:	58ms
get tp. blocked users:	1ms
others:	19ms

total:	173ms