Open saza-ku opened 12 months ago
+-------+--------+------------------------------------------+-------+-------+---------+-------+-------+
| COUNT | METHOD | URI | MIN | MAX | SUM | AVG | P99 |
+-------+--------+------------------------------------------+-------+-------+---------+-------+-------+
| 419 | GET | /api/livestream/search | 0.068 | 1.320 | 197.340 | 0.471 | 0.948 |
| 4318 | GET | /api/livestream/.+/livecomment | 0.004 | 0.112 | 100.108 | 0.023 | 0.056 |
| 4353 | GET | /api/livestream/.+/reaction | 0.004 | 0.084 | 99.760 | 0.023 | 0.056 |
| 4059 | POST | /api/livestream/.+/livecomment | 0.000 | 0.144 | 96.256 | 0.024 | 0.060 |
| 3951 | POST | /api/livestream/.+/reaction | 0.004 | 0.076 | 85.200 | 0.022 | 0.056 |
| 39575 | GET | /api/user/.+/icon | 0.004 | 0.032 | 63.376 | 0.002 | 0.008 |
| 566 | POST | /api/register | 0.004 | 0.176 | 58.684 | 0.104 | 0.160 |
| 473 | POST | /api/livestream/reservation | 0.024 | 0.140 | 29.812 | 0.063 | 0.112 |
| 603 | POST | /api/icon | 0.004 | 0.096 | 8.132 | 0.013 | 0.052 |
| 181 | GET | /api/livestream | 0.004 | 0.156 | 8.064 | 0.045 | 0.140 |
| 571 | POST | /api/login | 0.004 | 0.088 | 5.820 | 0.010 | 0.040 |
| 42 | GET | /api/user/.+/statistics | 0.024 | 0.216 | 5.484 | 0.131 | 0.216 |
| 216 | POST | /api/livestream/.+/moderate | 0.004 | 0.052 | 5.292 | 0.024 | 0.048 |
| 437 | GET | /api/livestream/.+/report | 0.004 | 0.104 | 4.576 | 0.010 | 0.040 |
| 490 | GET | /api/tag | 0.004 | 0.064 | 4.552 | 0.009 | 0.044 |
| 374 | DELETE | /api/livestream/.+/exit | 0.004 | 0.072 | 3.548 | 0.009 | 0.040 |
| 384 | POST | /api/livestream/.+/enter | 0.004 | 0.060 | 3.496 | 0.009 | 0.036 |
| 40 | GET | /api/user/.+/livestream | 0.032 | 0.120 | 3.284 | 0.082 | 0.120 |
| 324 | GET | /api/livestream/.+/ngwords | 0.004 | 0.068 | 2.956 | 0.009 | 0.040 |
| 25 | GET | /api/livestream/.+/statistics | 0.024 | 0.144 | 2.540 | 0.102 | 0.144 |
| 58 | POST | /api/livestream/.+/livecomment/.+/report | 0.004 | 0.104 | 1.592 | 0.027 | 0.104 |
| 41 | GET | /api/user/.+/theme | 0.004 | 0.044 | 0.420 | 0.010 | 0.044 |
| 6 | GET | /api/user/.+ | 0.004 | 0.004 | 0.016 | 0.003 | 0.004 |
| 1 | GET | /api/livestream/.+ | 0.004 | 0.004 | 0.004 | 0.004 | 0.004 |
| 1 | GET | /api/payment | 0.000 | 0.000 | 0.000 | 0.000 | 0.000 |
+-------+--------+------------------------------------------+-------+-------+---------+-------+-------+
Reading from STDIN ...
# No events processed.
http://localhost:19991/#menu_services;after=1700898320989;before=1700898397155
go tool pprof -http=0.0.0.0:1080 http://isucon1/pprof/11250745
+-------+--------+-----+-----+-----+-----+-----+-----+
| COUNT | METHOD | URI | MIN | MAX | SUM | AVG | P99 |
+-------+--------+-----+-----+-----+-----+-----+-----+
+-------+--------+-----+-----+-----+-----+-----+-----+
Count: 31831 Time=0.00s (60s) Lock=0.00s (0s) Rows=0.2 (6004), isudns[isudns]@isucon1
SELECT content,ttl,prio,type,domain_id,disabled,name,auth FROM records WHERE disabled=N and name='S' and domain_id=N
Count: 23163 Time=0.00s (40s) Lock=0.00s (0s) Rows=0.5 (11740), isudns[isudns]@isucon1
SELECT content,ttl,prio,type,domain_id,disabled,name,auth FROM records WHERE disabled=N and type='S' and name='S'
Count: 38700 Time=0.00s (4s) Lock=0.00s (0s) Rows=0.0 (0), isudns[isudns]@isucon1
select kind,content from domains, domainmetadata where domainmetadata.domain_id=domains.id and name='S'
Count: 564 Time=0.00s (2s) Lock=0.00s (0s) Rows=0.0 (0), isudns[isudns]@isucon1
delete from records where domain_id=N and name='S' and type='S'
Count: 1128 Time=0.00s (2s) Lock=0.00s (0s) Rows=0.5 (564), isudns[isudns]@isucon1
SELECT content,ttl,prio,type,domain_id,disabled,name,auth FROM records WHERE disabled=N and type='S' and name='S' and domain_id=N
Count: 94504 Time=0.00s (1s) Lock=0.00s (0s) Rows=0.0 (0), isudns[isudns]@isucon1
#
Count: 564 Time=0.00s (0s) Lock=0.00s (0s) Rows=0.0 (0), isudns[isudns]@isucon1
commit
Count: 564 Time=0.00s (0s) Lock=0.00s (0s) Rows=0.0 (0), isudns[isudns]@isucon1
insert into records (content,ttl,prio,type,domain_id,disabled,name,ordername,auth) values ('S',N,N,'S',N,N,'S',NULL,N)
Count: 564 Time=0.00s (0s) Lock=0.00s (0s) Rows=1.0 (564), isudns[isudns]@isucon1
select id,name,master,last_check,notified_serial,type,options,catalog,account from domains where name='S'
Count: 1128 Time=0.00s (0s) Lock=0.00s (0s) Rows=0.0 (0), isudns[isudns]@isucon1
SET SESSION TRANSACTION ISOLATION LEVEL READ COMMITTED
Reading from STDIN ...
# 11.9s user time, 70ms system time, 36.75M rss, 51.07M vsz
# Current date: Sat Nov 25 07:46:55 2023
# Hostname: ip-192-168-0-12
# Files: STDIN
# Overall: 193.28k total, 20 unique, 2.33k QPS, 1.35x concurrency ________
# Time range: 2023-11-25T07:45:20 to 2023-11-25T07:46:43
# Attribute total min max avg 95% stddev median
# ============ ======= ======= ======= ======= ======= ======= =======
# Exec time 112s 1us 14ms 578us 2ms 883us 89us
# Lock time 181ms 0 7ms 0 1us 28us 0
# Rows sent 18.43k 0 1 0.10 0.99 0.29 0
# Rows examine 84.93M 0 1.80k 460.76 1.69k 703.60 0
# Query size 14.88M 5 221 80.72 151.03 48.73 51.63
# Profile
# Rank Query ID Response time Calls R/Call V/M Item
# ==== ============================= ============= ===== ====== ===== ====
# 1 0x42EF7D7D98FBCC9723BF896E... 60.0907 53.8% 31831 0.0019 0.00 SELECT records
# 2 0x3D83BC87F3B3A00D571FFC81... 40.5552 36.3% 23163 0.0018 0.00 SELECT records
# 3 0x22279D81D51006139E0C7640... 4.8421 4.3% 38700 0.0001 0.00 SELECT domains domainmetadata
# 4 0x859BBB7E9D760686137A9444... 2.3849 2.1% 564 0.0042 0.00 DELETE records
# MISC 0xMISC 3.8595 3.5% 99023 0.0000 0.0 <16 ITEMS>
# Query 1: 468.10 QPS, 0.88x concurrency, ID 0x42EF7D7D98FBCC9723BF896EBFC51D24 at byte 43754519
# This item is included in the report because it matches --limit.
# Scores: V/M = 0.00
# Time range: 2023-11-25T07:45:21 to 2023-11-25T07:46:29
# Attribute pct total min max avg 95% stddev median
# ============ === ======= ======= ======= ======= ======= ======= =======
# Count 16 31831
# Exec time 53 60s 706us 12ms 2ms 3ms 629us 2ms
# Lock time 24 44ms 0 2ms 1us 1us 13us 1us
# Rows sent 31 5.86k 0 1 0.19 0.99 0.39 0
# Rows examine 56 47.68M 1.25k 1.80k 1.53k 1.69k 155.83 1.46k
# Query size 28 4.30M 129 221 141.69 158.58 11.85 136.99
# String:
# Hosts isucon1
# Users isudns
# Query_time distribution
# 1us
# 10us
# 100us #
# 1ms ################################################################
# 10ms #
# 100ms
# 1s
# 10s+
# Tables
# SHOW TABLE STATUS LIKE 'records'\G
# SHOW CREATE TABLE `records`\G
# EXPLAIN /*!50100 PARTITIONS*/
SELECT content,ttl,prio,type,domain_id,disabled,name,auth FROM records WHERE disabled=0 and name='pipe.u.isucon.dev' and domain_id=4\G
# EXPLAIN failed: DBD::mysql::st execute failed: Table 'isupipe.records' doesn't exist [for Statement "EXPLAIN SELECT content,ttl,prio,type,domain_id,disabled,name,auth FROM records WHERE disabled=0 and name='pipe.u.isucon.dev' and domain_id=4"] at /usr/bin/pt-query-digest line 7796.
# Query 2: 335.70 QPS, 0.59x concurrency, ID 0x3D83BC87F3B3A00D571FFC8104A6E50C at byte 19532768
# This item is included in the report because it matches --limit.
# Scores: V/M = 0.00
# Time range: 2023-11-25T07:45:20 to 2023-11-25T07:46:29
# Attribute pct total min max avg 95% stddev median
# ============ === ======= ======= ======= ======= ======= ======= =======
# Count 11 23163
# Exec time 36 41s 659us 10ms 2ms 3ms 587us 2ms
# Lock time 16 30ms 0 975us 1us 1us 6us 1us
# Rows sent 62 11.46k 0 1 0.51 0.99 0.50 0.99
# Rows examine 40 34.73M 1.25k 1.80k 1.54k 1.69k 155.94 1.53k
# Query size 20 2.99M 126 220 135.43 151.03 11.15 124.25
# String:
# Hosts isucon1
# Users isudns
# Query_time distribution
# 1us
# 10us
# 100us #
# 1ms ################################################################
# 10ms
# 100ms
# 1s
# 10s+
# Tables
# SHOW TABLE STATUS LIKE 'records'\G
# SHOW CREATE TABLE `records`\G
# EXPLAIN
SELECT content,ttl,prio,type,domain_id,disabled,name,auth FROM records WHERE disabled=0 and type='SOA' and name='u.isucon.dev'\G
# EXPLAIN failed: DBD::mysql::st execute failed: Table 'isupipe.records' doesn't exist [for Statement "EXPLAIN SELECT content,ttl,prio,type,domain_id,disabled,name,auth FROM records WHERE disabled=0 and type='SOA' and name='u.isucon.dev'"] at /usr/bin/pt-query-digest line 7796.
# Query 3: 569.12 QPS, 0.07x concurrency, ID 0x22279D81D51006139E0C76405B54C261 at byte 38666464
# This item is included in the report because it matches --limit.
# Scores: V/M = 0.00
# Time range: 2023-11-25T07:45:21 to 2023-11-25T07:46:29
# Attribute pct total min max avg 95% stddev median
# ============ === ======= ======= ======= ======= ======= ======= =======
# Count 20 38700
# Exec time 4 5s 72us 10ms 125us 167us 111us 108us
# Lock time 35 64ms 0 2ms 1us 1us 12us 1us
# Rows sent 0 0 0 0 0 0 0 0
# Rows examine 0 0 0 0 0 0 0 0
# Query size 28 4.21M 114 114 114 114 0 114
# String:
# Hosts isucon1
# Users isudns
# Query_time distribution
# 1us
# 10us ###########
# 100us ################################################################
# 1ms #
# 10ms
# 100ms
# 1s
# 10s+
# Tables
# SHOW TABLE STATUS LIKE 'domains'\G
# SHOW CREATE TABLE `domains`\G
# SHOW TABLE STATUS LIKE 'domainmetadata'\G
# SHOW CREATE TABLE `domainmetadata`\G
# EXPLAIN
select kind,content from domains, domainmetadata where domainmetadata.domain_id=domains.id and name='u.isucon.dev'\G
# EXPLAIN failed: DBD::mysql::st execute failed: Table 'isupipe.domains' doesn't exist [for Statement "EXPLAIN select kind,content from domains, domainmetadata where domainmetadata.domain_id=domains.id and name='u.isucon.dev'"] at /usr/bin/pt-query-digest line 7796.
# Query 4: 8.29 QPS, 0.04x concurrency, ID 0x859BBB7E9D760686137A944406874C4D at byte 51601466
# This item is included in the report because it matches --limit.
# Scores: V/M = 0.00
# Time range: 2023-11-25T07:45:21 to 2023-11-25T07:46:29
# Attribute pct total min max avg 95% stddev median
# ============ === ======= ======= ======= ======= ======= ======= =======
# Count 0 564
# Exec time 2 2s 2ms 14ms 4ms 7ms 1ms 4ms
# Lock time 22 41ms 0 7ms 72us 1us 495us 1us
# Rows sent 0 0 0 0 0 0 0 0
# Rows examine 0 860.04k 1.25k 1.80k 1.52k 1.69k 161.07 1.46k
# Query size 0 47.13k 78 93 85.57 88.31 3.28 84.10
# String:
# Hosts isucon1
# Users isudns
# Query_time distribution
# 1us
# 10us
# 100us
# 1ms ################################################################
# 10ms #
# 100ms
# 1s
# 10s+
# Tables
# SHOW TABLE STATUS LIKE 'records'\G
# SHOW CREATE TABLE `records`\G
delete from records where domain_id=4 and name='yamaguchiharuka0.u.isucon.dev' and type='A'\G
# Converted for EXPLAIN
# EXPLAIN
select * from records where domain_id=4 and name='yamaguchiharuka0.u.isucon.dev' and type='A'\G
http://localhost:19992/#menu_services;after=1700898320999;before=1700898401008
go tool pprof -http=0.0.0.0:1080 http://isucon2/pprof/11250745
+-------+--------+-----+-----+-----+-----+-----+-----+
| COUNT | METHOD | URI | MIN | MAX | SUM | AVG | P99 |
+-------+--------+-----+-----+-----+-----+-----+-----+
+-------+--------+-----+-----+-----+-----+-----+-----+
Count: 50698 Time=0.00s (40s) Lock=0.00s (0s) Rows=1.0 (50698), isucon[isucon]@isucon1
SELECT * FROM themes WHERE user_id = N
Count: 189061 Time=0.00s (16s) Lock=0.00s (0s) Rows=1.0 (189061), isucon[isucon]@isucon1
SELECT * FROM tags WHERE id = N
Count: 470 Time=0.02s (8s) Lock=0.00s (0s) Rows=0.0 (0), isucon[isucon]@isucon1
UPDATE reservation_slots SET slot = slot - N WHERE start_at >= N AND end_at <= N
Count: 471 Time=0.02s (7s) Lock=0.00s (0s) Rows=9.9 (4649), isucon[isucon]@isucon1
SELECT * FROM reservation_slots WHERE start_at >= N AND end_at <= N FOR UPDATE
Count: 41902 Time=0.00s (6s) Lock=0.00s (0s) Rows=4.5 (189070), isucon[isucon]@isucon1
SELECT * FROM livestream_tags WHERE livestream_id = N
Count: 50092 Time=0.00s (5s) Lock=0.00s (0s) Rows=1.0 (50092), isucon[isucon]@isucon1
SELECT * FROM users WHERE id = N
Count: 263 Time=0.02s (4s) Lock=0.00s (0s) Rows=3.3 (866), isucon[isucon]@isucon1
SELECT * FROM livestreams WHERE user_id = N
Count: 27101 Time=0.00s (2s) Lock=0.00s (0s) Rows=1.0 (27101), isucon[isucon]@isucon1
SELECT * FROM livestreams WHERE id = N
Count: 3161 Time=0.00s (2s) Lock=0.00s (0s) Rows=1.3 (4126), isucon[isucon]@isucon1
SELECT * FROM themes WHERE user_id IN (N, N)
Count: 21866 Time=0.00s (2s) Lock=0.00s (0s) Rows=0.0 (0), isucon[isucon]@isucon1
COMMIT
Reading from STDIN ...
# 27.6s user time, 100ms system time, 37.88M rss, 52.30M vsz
# Current date: Sat Nov 25 07:47:13 2023
# Hostname: ip-192-168-0-13
# Files: STDIN
# Overall: 460.66k total, 66 unique, 5.48k QPS, 1.39x concurrency ________
# Time range: 2023-11-25T07:45:21 to 2023-11-25T07:46:45
# Attribute total min max avg 95% stddev median
# ============ ======= ======= ======= ======= ======= ======= =======
# Exec time 117s 1us 95ms 253us 690us 1ms 76us
# Lock time 465ms 0 5ms 1us 1us 21us 1us
# Rows sent 1.66M 0 7.76k 3.77 4.96 89.60 0.99
# Rows examine 87.75M 0 14.40k 199.73 1.33k 634.40 0.99
# Query size 17.71M 6 447 40.30 72.65 26.96 31.70
# Profile
# Rank Query ID Response time Calls R/Call V/M Item
# ==== ============================ ============= ====== ====== ===== ====
# 1 0x38BC86A45F31C6B1EE32467... 40.6243 34.8% 50698 0.0008 0.00 SELECT themes
# 2 0xFBC5564AE716EAE82F20BFB... 17.0748 14.6% 189061 0.0001 0.00 SELECT tags
# 3 0xA3401CA3ABCC04C3AB221DB... 8.5823 7.3% 470 0.0183 0.00 UPDATE reservation_slots
# 4 0x7F9C0C0BA9473953B723EE1... 7.1349 6.1% 471 0.0151 0.00 SELECT reservation_slots
# MISC 0xMISC 43.4680 37.2% 219961 0.0002 0.0 <62 ITEMS>
# Query 1: 745.56 QPS, 0.60x concurrency, ID 0x38BC86A45F31C6B1EE324671506C898A at byte 103973579
# This item is included in the report because it matches --limit.
# Scores: V/M = 0.00
# Time range: 2023-11-25T07:45:21 to 2023-11-25T07:46:29
# Attribute pct total min max avg 95% stddev median
# ============ === ======= ======= ======= ======= ======= ======= =======
# Count 11 50698
# Exec time 34 41s 236us 14ms 801us 2ms 648us 596us
# Lock time 11 55ms 0 2ms 1us 1us 17us 1us
# Rows sent 2 49.51k 1 1 1 1 0 1
# Rows examine 70 62.20M 1000 1.53k 1.26k 1.46k 159.73 1.20k
# Query size 11 1.98M 38 41 40.88 40.45 0.60 40.45
# String:
# Hosts isucon1
# Users isucon
# Query_time distribution
# 1us
# 10us
# 100us ################################################################
# 1ms ############
# 10ms #
# 100ms
# 1s
# 10s+
# Tables
# SHOW TABLE STATUS LIKE 'themes'\G
# SHOW CREATE TABLE `themes`\G
# EXPLAIN /*!50100 PARTITIONS*/
SELECT * FROM themes WHERE user_id = 390\G
# *************************** 1. row ***************************
# id: 1
# select_type: SIMPLE
# table: themes
# partitions: NULL
# type: ALL
# possible_keys: NULL
# key: NULL
# key_len: NULL
# ref: NULL
# rows: 1563
# filtered: 10.00
# Extra: Using where
# Query 2: 2.78k QPS, 0.25x concurrency, ID 0xFBC5564AE716EAE82F20BFB45F6C37E7 at byte 33351075
# This item is included in the report because it matches --limit.
# Scores: V/M = 0.00
# Time range: 2023-11-25T07:45:21 to 2023-11-25T07:46:29
# Attribute pct total min max avg 95% stddev median
# ============ === ======= ======= ======= ======= ======= ======= =======
# Count 41 189061
# Exec time 14 17s 41us 11ms 90us 113us 158us 66us
# Lock time 46 214ms 0 5ms 1us 1us 26us 1us
# Rows sent 10 184.63k 1 1 1 1 0 1
# Rows examine 0 184.63k 1 1 1 1 0 1
# Query size 32 5.76M 31 33 31.94 31.70 0.45 31.70
# String:
# Hosts isucon1
# Users isucon
# Query_time distribution
# 1us
# 10us ################################################################
# 100us ####
# 1ms #
# 10ms #
# 100ms
# 1s
# 10s+
# Tables
# SHOW TABLE STATUS LIKE 'tags'\G
# SHOW CREATE TABLE `tags`\G
# EXPLAIN
SELECT * FROM tags WHERE id = 83\G
# *************************** 1. row ***************************
# id: 1
# select_type: SIMPLE
# table: tags
# partitions: NULL
# type: const
# possible_keys: PRIMARY
# key: PRIMARY
# key_len: 8
# ref: const
# rows: 1
# filtered: 100.00
# Extra: NULL
# Query 3: 6.91 QPS, 0.13x concurrency, ID 0xA3401CA3ABCC04C3AB221DB8AD5CBF26 at byte 71369262
# This item is included in the report because it matches --limit.
# Scores: V/M = 0.00
# Time range: 2023-11-25T07:45:21 to 2023-11-25T07:46:29
# Attribute pct total min max avg 95% stddev median
# ============ === ======= ======= ======= ======= ======= ======= =======
# Count 0 470
# Exec time 7 9s 5ms 51ms 18ms 33ms 8ms 16ms
# Lock time 0 444us 0 19us 0 1us 1us 1us
# Rows sent 0 0 0 0 0 0 0 0
# Rows examine 4 3.93M 8.55k 8.55k 8.55k 8.55k 0 8.55k
# Query size 0 44.98k 98 98 98 98 0 98
# String:
# Hosts isucon1
# Users isucon
# Query_time distribution
# 1us
# 10us
# 100us
# 1ms ###########
# 10ms ################################################################
# 100ms
# 1s
# 10s+
# Tables
# SHOW TABLE STATUS LIKE 'reservation_slots'\G
# SHOW CREATE TABLE `reservation_slots`\G
UPDATE reservation_slots SET slot = slot - 1 WHERE start_at >= 1703253600 AND end_at <= 1703304000\G
# Converted for EXPLAIN
# EXPLAIN
select slot = slot - 1 from reservation_slots where start_at >= 1703253600 AND end_at <= 1703304000\G
# Query 4: 6.93 QPS, 0.10x concurrency, ID 0x7F9C0C0BA9473953B723EE16C08655F1 at byte 108206795
# This item is included in the report because it matches --limit.
# Scores: V/M = 0.00
# Time range: 2023-11-25T07:45:21 to 2023-11-25T07:46:29
# Attribute pct total min max avg 95% stddev median
# ============ === ======= ======= ======= ======= ======= ======= =======
# Count 0 471
# Exec time 6 7s 5ms 46ms 15ms 30ms 7ms 13ms
# Lock time 0 528us 0 45us 1us 1us 2us 1us
# Rows sent 0 4.54k 1 21 9.87 18.53 5.79 9.83
# Rows examine 4 3.93M 8.55k 8.55k 8.55k 8.55k 0 8.55k
# Query size 0 44.16k 96 96 96 96 0 96
# String:
# Hosts isucon1
# Users isucon
# Query_time distribution
# 1us
# 10us
# 100us
# 1ms ##################
# 10ms ################################################################
# 100ms
# 1s
# 10s+
# Tables
# SHOW TABLE STATUS LIKE 'reservation_slots'\G
# SHOW CREATE TABLE `reservation_slots`\G
# EXPLAIN
SELECT * FROM reservation_slots WHERE start_at >= 1704528000 AND end_at <= 1704546000 FOR UPDATE\G
# *************************** 1. row ***************************
# id: 1
# select_type: SIMPLE
# table: reservation_slots
# partitions: NULL
# type: ALL
# possible_keys: NULL
# key: NULL
# key_len: NULL
# ref: NULL
# rows: 8593
# filtered: 11.11
# Extra: Using where
http://localhost:19993/#menu_services;after=1700898320998;before=1700898401005
go tool pprof -http=0.0.0.0:1080 http://isucon3/pprof/11250745
73000
84