MariaDB/query performance/externalstorage
< MariaDB | query performance
5.5.30-MariaDB-mariadb1~precise-log (master)
# 14.1s user time, 60ms system time, 28.68M rss, 100.35M vsz # Current date: Thu Jun 4 11:18:40 2015 # Hostname: es1009 # Files: slow.log # Overall: 55.21k total, 20 unique, 6.19 QPS, 0.00x concurrency __________ # Time range: 2015-06-04 08:46:43 to 11:15:26 # Attribute total min max avg 95% stddev median # ============ ======= ======= ======= ======= ======= ======= ======= # Exec time 44s 1us 99ms 798us 6ms 2ms 40us # Lock time 847ms 0 2ms 15us 54us 36us 0 # Rows sent 20.74k 0 426 0.38 0.99 7.83 0 # Rows examine 20.70k 0 426 0.38 0.99 7.83 0 # Query size 11.67M 14 195.06k 221.67 124.25 2.21k 65.89 # Profile # Rank Query ID Response time Calls R/Call V/M Item # ==== ================== ============= ===== ====== ===== =============== # 1 0xSANITIZED 42.7052 96.9% 12686 0.0034 0.00 SELECT blobs_cluster? # 2 0xSANITIZED 0.5627 1.3% 12827 0.0000 0.00 SET # 3 0xSANITIZED 0.5452 1.2% 2139 0.0003 0.00 INSERT blobs_cluster? # 4 0xSANITIZED 0.1589 0.4% 12564 0.0000 0.00 ADMIN INIT DB # 5 0xSANITIZED 0.0459 0.1% 2019 0.0000 0.00 SHOW MASTER STATUS # 6 0xSANITIZED 0.0202 0.0% 12804 0.0000 0.00 ADMIN QUIT # 7 0xSANITIZED 0.0135 0.0% 10 0.0014 0.00 SHOW VARIABLES # 8 0xSANITIZED 0.0090 0.0% 10 0.0009 0.00 SHOW GLOBAL STATUS # 9 0xSANITIZED 0.0039 0.0% 4 0.0010 0.00 SELECT INFORMATION_SCHEMA.INNODB_LOCK_WAITS INFORMATION_SCHEMA.INNODB_TRX INFORMATION_SCHEMA.PROCESSLIST # 10 0xSANITIZED 0.0039 0.0% 4 0.0010 0.00 SHOW INNODB STATUS # 11 0xSANITIZED 0.0031 0.0% 69 0.0000 0.00 SHOW SLAVE STATUS # 12 0xSANITIZED 0.0024 0.0% 10 0.0002 0.00 SELECT blobs_cluster? # 13 0xSANITIZED 0.0014 0.0% 28 0.0001 0.00 SELECT # 14 0xSANITIZED 0.0012 0.0% 7 0.0002 0.00 SELECT heartbeat.heartbeat # 15 0xSANITIZED 0.0005 0.0% 10 0.0001 0.00 SELECT # 16 0xSANITIZED 0.0004 0.0% 3 0.0001 0.00 SELECT blobs_cluster? # 17 0xSANITIZED 0.0003 0.0% 1 0.0003 0.00 SHOW STATUS # 18 0xSANITIZED 0.0003 0.0% 5 0.0001 0.00 SELECT # 19 0xSANITIZED 0.0002 0.0% 4 0.0001 0.00 SHOW PROCESSLIST # 20 0xSANITIZED 0.0002 0.0% 6 0.0000 0.00 SET # Query 1: 1.42 QPS, 0.00x concurrency, ID 0xSANITIZED at byte 12097671 # This item is included in the report because it matches --limit. # Scores: V/M = 0.00 # Time range: 2015-06-04 08:46:44 to 11:15:25 # Attribute pct total min max avg 95% stddev median # ============ === ======= ======= ======= ======= ======= ======= ======= # Count 22 12686 # Exec time 96 43s 98us 99ms 3ms 8ms 3ms 4ms # Lock time 75 636ms 29us 835us 50us 54us 9us 49us # Rows sent 59 12.39k 1 1 1 1 0 1 # Rows examine 59 12.39k 1 1 1 1 0 1 # Query size 12 1.50M 109 147 124.18 124.25 4.31 124.25 # String: # Databases wikidatawi... (2769/21%), enwiki (2298/18%)... 300 more # Hosts # Users wikiuser # Query_time distribution # 1us # 10us # # 100us ############################################## # 1ms ################################################################ # 10ms # # 100ms # # 1s # 10s+ # Tables # SHOW TABLE STATUS FROM `wikidatawiki` LIKE 'blobs_cluster25'\G # SHOW CREATE TABLE `wikidatawiki`.`blobs_cluster25`\G # EXPLAIN /*!50100 PARTITIONS*/ SELECT /* ExternalStoreDB::fetchBlob SANITIZED */ blob_text FROM `blobs_cluster25` WHERE blob_id = '85913556' LIMIT 1\G # Query 2: 1.44 QPS, 0.00x concurrency, ID 0xSANITIZED at byte 17589305 # This item is included in the report because it matches --limit. # Scores: V/M = 0.00 # Time range: 2015-06-04 08:46:44 to 11:15:26 # Attribute pct total min max avg 95% stddev median # ============ === ======= ======= ======= ======= ======= ======= ======= # Count 23 12827 # Exec time 1 563ms 29us 157us 43us 44us 5us 42us # Lock time 0 0 0 0 0 0 0 0 # Rows sent 0 0 0 0 0 0 0 0 # Rows examine 0 0 0 0 0 0 0 0 # Query size 6 826.74k 66 66 66 66 0 66 # String: # Databases wikidatawi... (2820/21%), enwiki (2144/16%)... 304 more # Hosts # Users wikiuser # Query_time distribution # 1us # 10us ################################################################ # 100us # # 1ms # 10ms # 100ms # 1s # 10s+ SET group_concat_max_len = 262144, `innodb_lock_wait_timeout` = 15\G # Query 3: 0.24 QPS, 0.00x concurrency, ID 0xSANITIZED at byte 20342005 # This item is included in the report because it matches --limit. # Scores: V/M = 0.00 # Time range: 2015-06-04 08:46:47 to 11:15:22 # Attribute pct total min max avg 95% stddev median # ============ === ======= ======= ======= ======= ======= ======= ======= # Count 3 2139 # Exec time 1 545ms 120us 9ms 254us 515us 409us 167us # Lock time 24 207ms 40us 2ms 96us 273us 123us 60us # Rows sent 0 0 0 0 0 0 0 0 # Rows examine 0 0 0 0 0 0 0 0 # Query size 73 8.55M 103 195.06k 4.09k 18.47k 10.53k 1.04k # String: # Databases enwiki (352/16%), wikidatawi... (262/12%)... 116 more # Hosts # Users wikiuser # Query_time distribution # 1us # 10us # 100us ################################################################ # 1ms # # 10ms # 100ms # 1s # 10s+ # Tables # SHOW TABLE STATUS FROM `huwikiquote` LIKE 'blobs_cluster25'\G # SHOW CREATE TABLE `huwikiquote`.`blobs_cluster25`\G INSERT /* ExternalStoreDB::store SANITIZED */ INTO `blobs_cluster25` (blob_id,blob_text) VALUES (NULL,'<blob contents>')\G # Query 4: 1.41 QPS, 0.00x concurrency, ID 0xSANITIZED at byte 19919781 # This item is included in the report because it matches --limit. # Scores: V/M = 0.00 # Time range: 2015-06-04 08:46:44 to 11:15:26 # Attribute pct total min max avg 95% stddev median # ============ === ======= ======= ======= ======= ======= ======= ======= # Count 22 12564 # Exec time 0 159ms 7us 88us 12us 13us 2us 12us # Lock time 0 0 0 0 0 0 0 0 # Rows sent 0 0 0 0 0 0 0 0 # Rows examine 0 0 0 0 0 0 0 0 # Query size 3 368.09k 30 30 30 30 0 30 # String: # Databases wikidatawi... (2810/22%), enwiki (2090/16%)... 287 more # Hosts # Users wikiuser # Query_time distribution # 1us # # 10us ################################################################ # 100us # 1ms # 10ms # 100ms # 1s # 10s+ administrator command: Init DB\G # Query 5: 0.23 QPS, 0.00x concurrency, ID 0xSANITIZED at byte 2893029 # This item is included in the report because it matches --limit. # Scores: V/M = 0.00 # Time range: 2015-06-04 08:46:43 to 11:15:25 # Attribute pct total min max avg 95% stddev median # ============ === ======= ======= ======= ======= ======= ======= ======= # Count 3 2019 # Exec time 0 46ms 19us 149us 22us 23us 4us 21us # Lock time 0 0 0 0 0 0 0 0 # Rows sent 0 0 0 0 0 0 0 0 # Rows examine 0 0 0 0 0 0 0 0 # Query size 1 120.27k 18 92 61.00 69.19 7.41 59.77 # String: # Databases enwiki (349/17%), wikidatawi... (258/12%)... 102 more # Hosts # Users wikiuser (1975/97%), watchdog (44/2%) # Query_time distribution # 1us # 10us ################################################################ # 100us # # 1ms # 10ms # 100ms # 1s # 10s+ SHOW /* DatabaseBase::getMasterPos SANITIZED */ MASTER STATUS\G
10.0.16-MariaDB-log (slave)
# 37.9s user time, 150ms system time, 32.64M rss, 104.39M vsz # Current date: Thu Jun 4 11:19:33 2015 # Hostname: es1008 # Files: slow.log # Overall: 167.80k total, 10 unique, 19.05 QPS, 0.01x concurrency ________ # Time range: 2015-06-04 08:48:13 to 11:15:03 # Attribute total min max avg 95% stddev median # ============ ======= ======= ======= ======= ======= ======= ======= # Exec time 122s 1us 164ms 729us 6ms 2ms 52us # Lock time 2s 0 325us 12us 54us 22us 0 # Rows sent 37.16k 0 22 0.23 0.99 0.43 0 # Rows examine 36.99k 0 22 0.23 0.99 0.43 0 # Query size 9.85M 17 362 61.56 124.25 37.32 65.89 # Profile # Rank Query ID Response time Calls R/Call V/M Item # ==== ================== ============== ===== ====== ===== ============== # 1 0xSANITIZED 119.0164 97.2% 37724 0.0032 0.00 SELECT blobs_cluster? # 2 0xSANITIZED 2.2171 1.8% 40188 0.0001 0.00 SET # 3 0xSANITIZED 0.5836 0.5% 40330 0.0000 0.00 ADMIN INIT DB # 4 0xSANITIZED 0.5269 0.4% 8729 0.0001 0.00 SHOW SLAVE STATUS # 5 0xSANITIZED 0.0777 0.1% 40549 0.0000 0.00 ADMIN QUIT # 6 0xSANITIZED 0.0140 0.0% 20 0.0007 0.00 SELECT blobs_cluster? # 7 0xSANITIZED 0.0105 0.0% 165 0.0001 0.00 SELECT # 8 0xSANITIZED 0.0062 0.0% 14 0.0004 0.00 SELECT blobs_cluster? # 9 0xSANITIZED 0.0025 0.0% 31 0.0001 0.00 SHOW ALL SLAVES # 10 0xSANITIZED 0.0017 0.0% 51 0.0000 0.00 SHOW MASTER STATUS # Query 1: 4.28 QPS, 0.01x concurrency, ID 0xSANITIZED at byte 44095294 # Scores: V/M = 0.00 # Time range: 2015-06-04 08:48:13 to 11:15:03 # Attribute pct total min max avg 95% stddev median # ============ === ======= ======= ======= ======= ======= ======= ======= # Count 22 37724 # Exec time 97 119s 88us 164ms 3ms 7ms 3ms 3ms # Lock time 99 2s 32us 325us 55us 63us 6us 54us # Rows sent 99 36.84k 1 1 1 1 0 1 # Rows examine 99 36.84k 1 1 1 1 0 1 # Query size 45 4.46M 108 148 124.11 124.25 4.40 124.25 # String: # Databases wikidatawi... (8064/21%), enwiki (6861/18%)... 401 more # Hosts # Users wikiuser # Query_time distribution # 1us # 10us # # 100us ################################################ # 1ms ################################################################ # 10ms # # 100ms # # 1s # 10s+ # Tables # SHOW TABLE STATUS FROM `wikidatawiki` LIKE 'blobs_cluster25'\G # SHOW CREATE TABLE `wikidatawiki`.`blobs_cluster25`\G # EXPLAIN /*!50100 PARTITIONS*/ SELECT /* ExternalStoreDB::fetchBlob SANITIZED */ blob_text FROM `blobs_cluster25` WHERE blob_id = '107270536' LIMIT 1\G # Query 2: 4.56 QPS, 0.00x concurrency, ID 0xSANITIZED at byte 27164714 # Scores: V/M = 0.00 # Time range: 2015-06-04 08:48:13 to 11:15:03 # Attribute pct total min max avg 95% stddev median # ============ === ======= ======= ======= ======= ======= ======= ======= # Count 23 40188 # Exec time 1 2s 34us 306us 55us 57us 5us 52us # Lock time 0 0 0 0 0 0 0 0 # Rows sent 0 0 0 0 0 0 0 0 # Rows examine 0 0 0 0 0 0 0 0 # Query size 25 2.53M 66 66 66 66 0 66 # String: # Databases wikidatawi... (8689/21%), enwiki (5456/13%)... 538 more # Hosts # Users wikiuser # Query_time distribution # 1us # 10us ################################################################ # 100us # # 1ms # 10ms # 100ms # 1s # 10s+ SET group_concat_max_len = 262144, `innodb_lock_wait_timeout` = 15\G # Query 3: 4.58 QPS, 0.00x concurrency, ID 0xSANITIZED at byte 37603515 # Scores: V/M = 0.00 # Time range: 2015-06-04 08:48:13 to 11:15:03 # Attribute pct total min max avg 95% stddev median # ============ === ======= ======= ======= ======= ======= ======= ======= # Count 24 40330 # Exec time 0 584ms 7us 265us 14us 17us 3us 13us # Lock time 0 0 0 0 0 0 0 0 # Rows sent 0 0 0 0 0 0 0 0 # Rows examine 0 0 0 0 0 0 0 0 # Query size 11 1.15M 30 30 30 30 0 30 # String: # Databases wikidatawi... (8742/21%), enwiki (5537/13%)... 530 more # Hosts # Users wikiuser # Query_time distribution # 1us # # 10us ################################################################ # 100us # # 1ms # 10ms # 100ms # 1s # 10s+ administrator command: Init DB\G # Query 4: 0.99 QPS, 0.00x concurrency, ID 0xSANITIZED at byte 43387714 # Scores: V/M = 0.00 # Time range: 2015-06-04 08:48:13 to 11:15:03 # Attribute pct total min max avg 95% stddev median # ============ === ======= ======= ======= ======= ======= ======= ======= # Count 5 8729 # Exec time 0 527ms 37us 225us 60us 63us 5us 57us # Lock time 0 0 0 0 0 0 0 0 # Rows sent 0 0 0 0 0 0 0 0 # Rows examine 0 0 0 0 0 0 0 0 # Query size 6 658.31k 17 97 77.23 76.28 3.78 76.28 # String: # Databases wikidatawi... (1060/12%), enwiki (318/3%)... 496 more # Hosts (8717/99%), localhost (12/0%) # Users wikiuser (8717/99%), nagios (12/0%) # Query_time distribution # 1us # 10us ################################################################ # 100us # # 1ms # 10ms # 100ms # 1s # 10s+ SHOW /* DatabaseMysqlBase::getLagFromSlaveStatus SANITIZED */ SLAVE STATUS\G # Query 5: 4.60 QPS, 0.00x concurrency, ID 0xSANITIZED at byte 6338462 # Scores: V/M = 0.00 # Time range: 2015-06-04 08:48:13 to 11:15:03 # Attribute pct total min max avg 95% stddev median # ============ === ======= ======= ======= ======= ======= ======= ======= # Count 24 40549 # Exec time 0 78ms 1us 112us 1us 1us 0 1us # Lock time 0 0 0 0 0 0 0 0 # Rows sent 0 0 0 0 0 0 0 0 # Rows examine 0 0 0 0 0 0 0 0 # Query size 10 1.04M 27 27 27 27 0 27 # String: # Databases wikidatawi... (8666/21%), enwiki (5563/13%)... 531 more # Hosts (40533/99%), localhost (16/0%) # Users wikiuser (40453/99%), watchdog (80/0%)... 1 more # Query_time distribution # 1us ################################################################ # 10us # # 100us # # 1ms # 10ms # 100ms # 1s # 10s+ administrator command: Quit\G