*Hi,
In our production lab we found one issue that the bin log update got interrupted and hung there for minutes after C++ API mysql_query with "commit" param returned success.
Timeline:*
1. Transaction committed by calling of mysql_query with "commit" param which returned success (product log which confirmed successful return of mysql_query):
+++ 2022/05/26 11:32:46.065 DB MEDIUM remotedb:11310 E:105110 S:2657 (RemotedbNotify.cpp 1026 v-0001 169.254.64.1 oam-a)
INFO: Completed notify connection attempt.
2. bin log started to be updated, but only updated the first 3 records:
- at 2296562
#220526 11:32:46 server id 1 end_log_pos 2296604 CRC32 0x30537424 GTID 0-1-29252 trans
/Unable to render embedded object: File (100001 SET @@session.gtid_seq_no=29252*//*) not found./;
START TRANSACTION
/!/;
- at 2296604
#220526 11:32:46 server id 1 end_log_pos 2297037 CRC32 0xed2efe0a Query thread_id=11783 exec_time=0 error_code=0
SET TIMESTAMP=1653564766/!/;
update db01host.notify_remdb_operation set process_id = 11312, objlen = 48, flag = 0, socket = 171, retry_count = 0, host_ip = x'00a9fe4101000000000000000000000000000000', schema_class = 'cfg' where operation = 1 and notification_type = 2 and remote_ip = x'00a9fe4001000000000000000000000000000000' and table_name = 'resource_parms' and process_name = 'SSPcntl_agt'
/!/;
- at 2297037
#220526 11:32:46 server id 1 end_log_pos 2297470 CRC32 0xa716708c Query thread_id=11783 exec_time=0 error_code=0
SET TIMESTAMP=1653564766/!/;
update db01host.notify_remdb_operation set process_id = 11312, objlen = 48, flag = 0, socket = 171, retry_count = 0, host_ip = x'00a9fe4101000000000000000000000000000000', schema_class = 'cfg' where operation = 2 and notification_type = 2 and remote_ip = x'00a9fe4001000000000000000000000000000000' and table_name = 'resource_parms' and process_name = 'SSPcntl_agt'
/!/;
- at 2297470
#220526 11:32:46 server id 1 end_log_pos 2297903 CRC32 0x3144ec9e Query thread_id=11783 exec_time=0 error_code=0
SET TIMESTAMP=1653564766/!/;
update db01host.notify_remdb_operation set process_id = 11312, objlen = 48, flag = 0, socket = 171, retry_count = 0, host_ip = x'00a9fe4101000000000000000000000000000000', schema_class = 'cfg' where operation = 3 and notification_type = 2 and remote_ip = x'00a9fe4001000000000000000000000000000000' and table_name = 'resource_parms' and process_name = 'SSPcntl_agt'
/!/;
- at 2297903
#220526 11:32:46 server id 1 end_log_pos 2297934 CRC32 0x3214e514 Xid = 249264
COMMIT/!/;
*3. About >6m later, it started to write bin log for the remaining records for that transaction (Note in-between there were bin logs writes for other transactions): *
- at 2443522
#220526 11:39:18 server id 1 end_log_pos 2443961 CRC32 0xd83cc209 Query thread_id=72 exec_time=0 error_code=0
SET TIMESTAMP=1653565158/!/;
update db01host.notify_remdb_operation set process_id = 11312, objlen = 824, flag = 0, socket = 171, retry_count = 0, host_ip = x'00a9fe4001000000000000000000000000000000', schema_class = 'cfg' where operation = 1 and notification_type = 2 and remote_ip = x'00a9fe4001000000000000000000000000000000' and table_name = 'gateway_family_base' and process_name = 'SSPcntl_agt'
/!/;
......
- at 2498796
#220526 11:39:20 server id 1 end_log_pos 2499229 CRC32 0x2b4c10a9 Query thread_id=72 exec_time=0 error_code=0
SET TIMESTAMP=1653565160/!/;
update db01host.notify_remdb_operation set process_id = 11312, objlen = 48, flag = 0, socket = 171, retry_count = 0, host_ip = x'00a9fe4001000000000000000000000000000000', schema_class = 'cfg' where operation = 3 and notification_type = 2 and remote_ip = x'00a9fe4001000000000000000000000000000000' and table_name = 'resource_parms' and process_name = 'SSPcntl_agt'
/!/;
Then finally the DB contents were overwritten incorrectly by the delayed bin log updates causing product failures.
*Mariadb Server version: 10.4.21-MariaDB-log Source distribution
Attached config file my.cnf and bin log as well as decoded bin log.
Your advice is highly appreciated.*
{"report":{"fcp":1449.2999999970198,"ttfb":288.29999999701977,"pageVisibility":"visible","entityId":111369,"key":"jira.project.issue.view-issue","isInitial":true,"threshold":1000,"elementTimings":{},"userDeviceMemory":8,"userDeviceProcessors":64,"apdex":0.5,"journeyId":"5b969495-d819-4018-a271-4cd88c2d677e","navigationType":0,"readyForUser":1526.0999999940395,"redirectCount":0,"resourceLoadedEnd":2059.5999999940395,"resourceLoadedStart":293.59999999403954,"resourceTiming":[{"duration":644.7000000029802,"initiatorType":"link","name":"https://jira.mariadb.org/s/2c21342762a6a02add1c328bed317ffd-CDN/lu2bv2/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/css/_super/batch.css","startTime":293.59999999403954,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":293.59999999403954,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":938.2999999970198,"responseStart":0,"secureConnectionStart":0},{"duration":645,"initiatorType":"link","name":"https://jira.mariadb.org/s/7ebd35e77e471bc30ff0eba799ebc151-CDN/lu2bv2/820016/12ta74/2380add21a9a1006587582385952de73/_/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","startTime":293.90000000596046,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":293.90000000596046,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":938.9000000059605,"responseStart":0,"secureConnectionStart":0},{"duration":703,"initiatorType":"script","name":"https://jira.mariadb.org/s/e9b27a47da5fb0f74a35acd57e9847fb-CDN/lu2bv2/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/js/_super/batch.js?locale=en","startTime":294.09999999403954,"connectEnd":294.09999999403954,"connectStart":294.09999999403954,"domainLookupEnd":294.09999999403954,"domainLookupStart":294.09999999403954,"fetchStart":294.09999999403954,"redirectEnd":0,"redirectStart":0,"requestStart":294.09999999403954,"responseEnd":997.0999999940395,"responseStart":997.0999999940395,"secureConnectionStart":294.09999999403954},{"duration":777.1000000089407,"initiatorType":"script","name":"https://jira.mariadb.org/s/c32eb0da7ad9831253f8397e6cc26afd-CDN/lu2bv2/820016/12ta74/2380add21a9a1006587582385952de73/_/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","startTime":294.29999999701977,"connectEnd":294.29999999701977,"connectStart":294.29999999701977,"domainLookupEnd":294.29999999701977,"domainLookupStart":294.29999999701977,"fetchStart":294.29999999701977,"redirectEnd":0,"redirectStart":0,"requestStart":294.29999999701977,"responseEnd":1071.4000000059605,"responseStart":1071.2999999970198,"secureConnectionStart":294.29999999701977},{"duration":780.4000000059605,"initiatorType":"script","name":"https://jira.mariadb.org/s/bc0bcb146314416123c992714ee00ff7-CDN/lu2bv2/820016/12ta74/c92c0caa9a024ae85b0ebdbed7fb4bd7/_/download/contextbatch/js/atl.global,-_super/batch.js?locale=en","startTime":294.5,"connectEnd":294.5,"connectStart":294.5,"domainLookupEnd":294.5,"domainLookupStart":294.5,"fetchStart":294.5,"redirectEnd":0,"redirectStart":0,"requestStart":294.5,"responseEnd":1074.9000000059605,"responseStart":1074.9000000059605,"secureConnectionStart":294.5},{"duration":780.7999999970198,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bv2/820016/12ta74/1.0/_/download/batch/jira.webresources:calendar-en/jira.webresources:calendar-en.js","startTime":294.70000000298023,"connectEnd":294.70000000298023,"connectStart":294.70000000298023,"domainLookupEnd":294.70000000298023,"domainLookupStart":294.70000000298023,"fetchStart":294.70000000298023,"redirectEnd":0,"redirectStart":0,"requestStart":294.70000000298023,"responseEnd":1075.5,"responseStart":1075.5,"secureConnectionStart":294.70000000298023},{"duration":781,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bv2/820016/12ta74/1.0/_/download/batch/jira.webresources:calendar-localisation-moment/jira.webresources:calendar-localisation-moment.js","startTime":294.90000000596046,"connectEnd":294.90000000596046,"connectStart":294.90000000596046,"domainLookupEnd":294.90000000596046,"domainLookupStart":294.90000000596046,"fetchStart":294.90000000596046,"redirectEnd":0,"redirectStart":0,"requestStart":294.90000000596046,"responseEnd":1075.9000000059605,"responseStart":1075.9000000059605,"secureConnectionStart":294.90000000596046},{"duration":846,"initiatorType":"link","name":"https://jira.mariadb.org/s/b04b06a02d1959df322d9cded3aeecc1-CDN/lu2bv2/820016/12ta74/a2ff6aa845ffc9a1d22fe23d9ee791fc/_/download/contextbatch/css/jira.global.look-and-feel,-_super/batch.css","startTime":295.09999999403954,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":295.09999999403954,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1141.0999999940395,"responseStart":0,"secureConnectionStart":0},{"duration":781.2000000029802,"initiatorType":"script","name":"https://jira.mariadb.org/rest/api/1.0/shortcuts/820016/47140b6e0a9bc2e4913da06536125810/shortcuts.js?context=issuenavigation&context=issueaction","startTime":295.20000000298023,"connectEnd":295.20000000298023,"connectStart":295.20000000298023,"domainLookupEnd":295.20000000298023,"domainLookupStart":295.20000000298023,"fetchStart":295.20000000298023,"redirectEnd":0,"redirectStart":0,"requestStart":295.20000000298023,"responseEnd":1076.4000000059605,"responseStart":1076.4000000059605,"secureConnectionStart":295.20000000298023},{"duration":845.8999999910593,"initiatorType":"link","name":"https://jira.mariadb.org/s/3ac36323ba5e4eb0af2aa7ac7211b4bb-CDN/lu2bv2/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":295.40000000596046,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":295.40000000596046,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":1141.2999999970198,"responseStart":0,"secureConnectionStart":0},{"duration":781.5999999940395,"initiatorType":"script","name":"https://jira.mariadb.org/s/719848dd97ebe0663199f49a3936487a-CDN/lu2bv2/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":295.5,"connectEnd":295.5,"connectStart":295.5,"domainLookupEnd":295.5,"domainLookupStart":295.5,"fetchStart":295.5,"redirectEnd":0,"redirectStart":0,"requestStart":295.5,"responseEnd":1077.0999999940395,"responseStart":1077.0999999940395,"secureConnectionStart":295.5},{"duration":1762.3999999910593,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bv2/820016/12ta74/1.0/_/download/batch/jira.webresources:bigpipe-js/jira.webresources:bigpipe-js.js","startTime":296.40000000596046,"connectEnd":296.40000000596046,"connectStart":296.40000000596046,"domainLookupEnd":296.40000000596046,"domainLookupStart":296.40000000596046,"fetchStart":296.40000000596046,"redirectEnd":0,"redirectStart":0,"requestStart":296.40000000596046,"responseEnd":2058.7999999970198,"responseStart":2058.7999999970198,"secureConnectionStart":296.40000000596046},{"duration":1763.0999999940395,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bv2/820016/12ta74/1.0/_/download/batch/jira.webresources:bigpipe-init/jira.webresources:bigpipe-init.js","startTime":296.5,"connectEnd":296.5,"connectStart":296.5,"domainLookupEnd":296.5,"domainLookupStart":296.5,"fetchStart":296.5,"redirectEnd":0,"redirectStart":0,"requestStart":296.5,"responseEnd":2059.5999999940395,"responseStart":2059.5999999940395,"secureConnectionStart":296.5},{"duration":610.7999999970198,"initiatorType":"xmlhttprequest","name":"https://jira.mariadb.org/rest/webResources/1.0/resources","startTime":1152.9000000059605,"connectEnd":1152.9000000059605,"connectStart":1152.9000000059605,"domainLookupEnd":1152.9000000059605,"domainLookupStart":1152.9000000059605,"fetchStart":1152.9000000059605,"redirectEnd":0,"redirectStart":0,"requestStart":1152.9000000059605,"responseEnd":1763.7000000029802,"responseStart":1763.7000000029802,"secureConnectionStart":1152.9000000059605},{"duration":674.6000000089407,"initiatorType":"script","name":"https://www.google-analytics.com/analytics.js","startTime":1442.7999999970198,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":1442.7999999970198,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":2117.4000000059605,"responseStart":0,"secureConnectionStart":0}],"fetchStart":0,"domainLookupStart":0,"domainLookupEnd":0,"connectStart":0,"connectEnd":0,"requestStart":131,"responseStart":289,"responseEnd":290,"domLoading":292,"domInteractive":2076,"domContentLoadedEventStart":2076,"domContentLoadedEventEnd":2114,"domComplete":3389,"loadEventStart":3389,"loadEventEnd":3391,"userAgent":"Mozilla/5.0 AppleWebKit/537.36 (KHTML, like Gecko; compatible; ClaudeBot/1.0; +claudebot@anthropic.com)","marks":[{"name":"bigPipe.sidebar-id.start","time":2064.0999999940395},{"name":"bigPipe.sidebar-id.end","time":2065},{"name":"bigPipe.activity-panel-pipe-id.start","time":2065.0999999940395},{"name":"bigPipe.activity-panel-pipe-id.end","time":2067.5},{"name":"activityTabFullyLoaded","time":2122.7999999970198}],"measures":[],"correlationId":"6cba32773ea51a","effectiveType":"4g","downlink":10,"rtt":0,"serverDuration":100,"dbReadsTimeInMs":10,"dbConnsTimeInMs":17,"applicationHash":"9d11dbea5f4be3d4cc21f03a88dd11d8c8687422","experiments":[]}}