While working on MDEV-33894, I noticed a rather deep call stack:
#0 __libc_pwrite64 (fd=7, buf=0x7f0d44b15000, count=count@entry=8192, offset=104448) at ../sysdeps/unix/sysv/linux/pwrite64.c:24
|
#1 0x0000564264c10e07 in SyncFileIO::execute_low (this=this@entry=0x7f0d1729bc20, request=..., n=8192) at /data/Server/10.11-MDEV-33894/storage/innobase/os/os0file.cc:692
|
#2 0x0000564264c10f17 in SyncFileIO::execute (this=this@entry=0x7f0d1729bc20, request=...) at /data/Server/10.11-MDEV-33894/storage/innobase/os/os0file.cc:715
|
#3 0x0000564264c12402 in os_file_io (in_type=..., file=file@entry=7, buf=buf@entry=0x7f0d44b15000, n=n@entry=8192, offset=offset@entry=104448, err=err@entry=0x7f0d1729be7c)
|
at /data/Server/10.11-MDEV-33894/storage/innobase/os/os0file.cc:2584
|
#4 0x0000564264c125d9 in os_file_pwrite (type=..., file=file@entry=7, buf=buf@entry=0x7f0d44b15000 "w\a\f", n=8192, offset=104448, err=err@entry=0x7f0d1729be7c)
|
at /data/Server/10.11-MDEV-33894/storage/innobase/os/os0file.cc:2659
|
#5 0x0000564264c14299 in os_file_write_func (type=..., name=<optimized out>, name@entry=0x56426540609f "ib_logfile0", file=7, buf=0x7f0d44b15000, offset=<optimized out>, offset@entry=104448, n=<optimized out>)
|
at /data/Server/10.11-MDEV-33894/storage/innobase/os/os0file.cc:2688
|
#6 0x0000564264bbed28 in log_file_t::write (this=this@entry=0x5642665238c0 <log_sys+128>, offset=offset@entry=104448, buf=...) at /data/Server/10.11-MDEV-33894/storage/innobase/log/log0log.cc:173
|
#7 0x0000564264bbf1a7 in log_write_buf (buf=buf@entry=0x7f0d44b15000 "w\a\f", length=length@entry=8192, offset=offset@entry=104448) at /data/Server/10.11-MDEV-33894/storage/innobase/log/log0log.cc:624
|
#8 0x0000564264bc1936 in log_t::write_buf<true> (this=this@entry=0x564266523840 <log_sys>) at /data/Server/10.11-MDEV-33894/storage/innobase/log/log0log.cc:944
|
The InnoDB write-ahead log is not instrumented by PERFORMANCE_SCHEMA; we only have innodb_data_file_key and innodb_temp_file_key for data files and temporary files. Therefore, log_file_t::write could simply invoke IF_WIN(tpool::,)pwrite().
- relates to
-
MDEV-33894
MariaDB does unexpected storage read IO for the redo log
-
-
Closed
{"report":{"fcp":2051.199999809265,"ttfb":356.2999997138977,"pageVisibility":"visible","entityId":129710,"key":"jira.project.issue.view-issue","isInitial":true,"threshold":1000,"elementTimings":{},"userDeviceMemory":8,"userDeviceProcessors":64,"apdex":0.5,"journeyId":"53edcaae-838f-4ab0-9ec1-60fed7417901","navigationType":0,"readyForUser":2149.8999996185303,"redirectCount":0,"resourceLoadedEnd":2575.3999996185303,"resourceLoadedStart":425.19999980926514,"resourceTiming":[{"duration":852.5,"initiatorType":"link","name":"https://jira.mariadb.org/s/2c21342762a6a02add1c328bed317ffd-CDN/lu2cib/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/css/_super/batch.css","startTime":425.19999980926514,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":425.19999980926514,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1277.6999998092651,"responseStart":0,"secureConnectionStart":0},{"duration":852.5,"initiatorType":"link","name":"https://jira.mariadb.org/s/7ebd35e77e471bc30ff0eba799ebc151-CDN/lu2cib/820016/12ta74/494e4c556ecbb29f90a3d3b4f09cb99c/_/download/contextbatch/css/jira.browse.project,project.issue.navigator,jira.view.issue,jira.general,jira.global,atl.general,-_super/batch.css?agile_global_admin_condition=true&jag=true&jira.create.linked.issue=true&slack-enabled=true&whisper-enabled=true","startTime":425.5,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":425.5,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1278,"responseStart":0,"secureConnectionStart":0},{"duration":853.1999998092651,"initiatorType":"script","name":"https://jira.mariadb.org/s/0917945aaa57108d00c5076fea35e069-CDN/lu2cib/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/js/_super/batch.js?locale=en","startTime":426,"connectEnd":426,"connectStart":426,"domainLookupEnd":426,"domainLookupStart":426,"fetchStart":426,"redirectEnd":0,"redirectStart":0,"requestStart":426,"responseEnd":1279.1999998092651,"responseStart":1279.1999998092651,"secureConnectionStart":426},{"duration":943.4000000953674,"initiatorType":"script","name":"https://jira.mariadb.org/s/2d8175ec2fa4c816e8023260bd8c1786-CDN/lu2cib/820016/12ta74/494e4c556ecbb29f90a3d3b4f09cb99c/_/download/contextbatch/js/jira.browse.project,project.issue.navigator,jira.view.issue,jira.general,jira.global,atl.general,-_super/batch.js?agile_global_admin_condition=true&jag=true&jira.create.linked.issue=true&locale=en&slack-enabled=true&whisper-enabled=true","startTime":426.19999980926514,"connectEnd":426.19999980926514,"connectStart":426.19999980926514,"domainLookupEnd":426.19999980926514,"domainLookupStart":426.19999980926514,"fetchStart":426.19999980926514,"redirectEnd":0,"redirectStart":0,"requestStart":426.19999980926514,"responseEnd":1369.5999999046326,"responseStart":1369.5,"secureConnectionStart":426.19999980926514},{"duration":1088.6000003814697,"initiatorType":"script","name":"https://jira.mariadb.org/s/a9324d6758d385eb45c462685ad88f1d-CDN/lu2cib/820016/12ta74/c92c0caa9a024ae85b0ebdbed7fb4bd7/_/download/contextbatch/js/atl.global,-_super/batch.js?locale=en","startTime":426.3999996185303,"connectEnd":426.3999996185303,"connectStart":426.3999996185303,"domainLookupEnd":426.3999996185303,"domainLookupStart":426.3999996185303,"fetchStart":426.3999996185303,"redirectEnd":0,"redirectStart":0,"requestStart":426.3999996185303,"responseEnd":1515,"responseStart":1515,"secureConnectionStart":426.3999996185303},{"duration":1117,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2cib/820016/12ta74/1.0/_/download/batch/jira.webresources:calendar-en/jira.webresources:calendar-en.js","startTime":426.69999980926514,"connectEnd":426.69999980926514,"connectStart":426.69999980926514,"domainLookupEnd":426.69999980926514,"domainLookupStart":426.69999980926514,"fetchStart":426.69999980926514,"redirectEnd":0,"redirectStart":0,"requestStart":426.69999980926514,"responseEnd":1543.6999998092651,"responseStart":1543.6999998092651,"secureConnectionStart":426.69999980926514},{"duration":1132,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2cib/820016/12ta74/1.0/_/download/batch/jira.webresources:calendar-localisation-moment/jira.webresources:calendar-localisation-moment.js","startTime":427.09999990463257,"connectEnd":427.09999990463257,"connectStart":427.09999990463257,"domainLookupEnd":427.09999990463257,"domainLookupStart":427.09999990463257,"fetchStart":427.09999990463257,"redirectEnd":0,"redirectStart":0,"requestStart":427.09999990463257,"responseEnd":1559.0999999046326,"responseStart":1559.0999999046326,"secureConnectionStart":427.09999990463257},{"duration":1242.5,"initiatorType":"link","name":"https://jira.mariadb.org/s/b04b06a02d1959df322d9cded3aeecc1-CDN/lu2cib/820016/12ta74/a2ff6aa845ffc9a1d22fe23d9ee791fc/_/download/contextbatch/css/jira.global.look-and-feel,-_super/batch.css","startTime":427.2999997138977,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":427.2999997138977,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1669.7999997138977,"responseStart":0,"secureConnectionStart":0},{"duration":1159.1999998092651,"initiatorType":"script","name":"https://jira.mariadb.org/rest/api/1.0/shortcuts/820016/47140b6e0a9bc2e4913da06536125810/shortcuts.js?context=issuenavigation&context=issueaction","startTime":427.5,"connectEnd":427.5,"connectStart":427.5,"domainLookupEnd":427.5,"domainLookupStart":427.5,"fetchStart":427.5,"redirectEnd":0,"redirectStart":0,"requestStart":427.5,"responseEnd":1586.6999998092651,"responseStart":1586.6999998092651,"secureConnectionStart":427.5},{"duration":1242.5,"initiatorType":"link","name":"https://jira.mariadb.org/s/3ac36323ba5e4eb0af2aa7ac7211b4bb-CDN/lu2cib/820016/12ta74/d176f0986478cc64f24226b3d20c140d/_/download/contextbatch/css/com.atlassian.jira.projects.sidebar.init,-_super,-project.issue.navigator,-jira.view.issue/batch.css?jira.create.linked.issue=true","startTime":427.69999980926514,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":427.69999980926514,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1670.1999998092651,"responseStart":0,"secureConnectionStart":0},{"duration":1161,"initiatorType":"script","name":"https://jira.mariadb.org/s/5d5e8fe91fbc506585e83ea3b62ccc4b-CDN/lu2cib/820016/12ta74/d176f0986478cc64f24226b3d20c140d/_/download/contextbatch/js/com.atlassian.jira.projects.sidebar.init,-_super,-project.issue.navigator,-jira.view.issue/batch.js?jira.create.linked.issue=true&locale=en","startTime":427.8999996185303,"connectEnd":427.8999996185303,"connectStart":427.8999996185303,"domainLookupEnd":427.8999996185303,"domainLookupStart":427.8999996185303,"fetchStart":427.8999996185303,"redirectEnd":0,"redirectStart":0,"requestStart":427.8999996185303,"responseEnd":1588.8999996185303,"responseStart":1588.8999996185303,"secureConnectionStart":427.8999996185303},{"duration":2145.800000190735,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2cib/820016/12ta74/1.0/_/download/batch/jira.webresources:bigpipe-js/jira.webresources:bigpipe-js.js","startTime":428.69999980926514,"connectEnd":428.69999980926514,"connectStart":428.69999980926514,"domainLookupEnd":428.69999980926514,"domainLookupStart":428.69999980926514,"fetchStart":428.69999980926514,"redirectEnd":0,"redirectStart":0,"requestStart":428.69999980926514,"responseEnd":2574.5,"responseStart":2574.5,"secureConnectionStart":428.69999980926514},{"duration":2146.5999999046326,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2cib/820016/12ta74/1.0/_/download/batch/jira.webresources:bigpipe-init/jira.webresources:bigpipe-init.js","startTime":428.7999997138977,"connectEnd":428.7999997138977,"connectStart":428.7999997138977,"domainLookupEnd":428.7999997138977,"domainLookupStart":428.7999997138977,"fetchStart":428.7999997138977,"redirectEnd":0,"redirectStart":0,"requestStart":428.7999997138977,"responseEnd":2575.3999996185303,"responseStart":2575.3999996185303,"secureConnectionStart":428.7999997138977},{"duration":884.5,"initiatorType":"xmlhttprequest","name":"https://jira.mariadb.org/rest/webResources/1.0/resources","startTime":1690.5,"connectEnd":1690.5,"connectStart":1690.5,"domainLookupEnd":1690.5,"domainLookupStart":1690.5,"fetchStart":1690.5,"redirectEnd":0,"redirectStart":0,"requestStart":1690.5,"responseEnd":2575,"responseStart":2575,"secureConnectionStart":1690.5},{"duration":545.4000000953674,"initiatorType":"script","name":"https://www.google-analytics.com/analytics.js","startTime":2039.6999998092651,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":2039.6999998092651,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":2585.0999999046326,"responseStart":0,"secureConnectionStart":0}],"fetchStart":0,"domainLookupStart":0,"domainLookupEnd":0,"connectStart":0,"connectEnd":0,"requestStart":200,"responseStart":357,"responseEnd":422,"domLoading":423,"domInteractive":2913,"domContentLoadedEventStart":2913,"domContentLoadedEventEnd":2976,"domComplete":4166,"loadEventStart":4166,"loadEventEnd":4167,"userAgent":"Mozilla/5.0 AppleWebKit/537.36 (KHTML, like Gecko; compatible; ClaudeBot/1.0; +claudebot@anthropic.com)","marks":[{"name":"bigPipe.sidebar-id.start","time":2593.0999999046326},{"name":"bigPipe.sidebar-id.end","time":2594},{"name":"bigPipe.activity-panel-pipe-id.start","time":2594.199999809265},{"name":"bigPipe.activity-panel-pipe-id.end","time":2619.0999999046326},{"name":"activityTabFullyLoaded","time":2983.5}],"measures":[],"correlationId":"fabc399d561cf4","effectiveType":"4g","downlink":10,"rtt":0,"serverDuration":99,"dbReadsTimeInMs":16,"dbConnsTimeInMs":27,"applicationHash":"9d11dbea5f4be3d4cc21f03a88dd11d8c8687422","experiments":[]}}
