Type:
Bug
Priority:
Major
Resolution:
Cannot Reproduce
Affects Version/s:
10.2.34 , 10.3.25 , 10.4.15 , 10.5.6
Environment:
kvm-deb-jessie-x86
galera_3nodes.galera_ist_gcache_rollover failed on BB10.2 : WSREP: exception caused by message:
{v=0,t=1,ut=255,o=4,s=0,sr=0,as=1,f=6,src=50524cfe,srcvid=view_id(REG,50524cfe,4),insvid=view_id(UNKNOWN,00000000,0),ru=00000000,r=[-1,-1],fs=75,nl=(}
stdio.log :
10.2.34 8cf8ad86d4b6f3479d80f3d8e8c2bcf463966924
galera_3nodes.galera_ist_gcache_rollover 'innodb' w1 [ fail ]
Test ended at 2020-08-25 11:48:06
CURRENT_TEST: galera_3nodes.galera_ipv6_xtrabackup-v2
CURRENT_TEST: galera_3nodes.galera_ipv6_xtrabackup-v2
CURRENT_TEST: galera_3nodes.galera_ist_gcache_rollover
Failed to start mysqld.3
- skipping '/dev/shm/var/1/log/galera_3nodes.galera_ist_gcache_rollover-innodb/'
Retrying test galera_3nodes.galera_ist_gcache_rollover, attempt(2/3)...
***Warnings generated in error logs during shutdown after running tests: galera_3nodes.galera_ist_gcache_rollover
2020-08-25 11:48:04 3054057280 [ERROR] WSREP: caught exception in PC, state dump to stderr follows:
2020-08-25 11:48:04 3054057280 [ERROR] WSREP: exception caused by message: {v=0,t=1,ut=255,o=4,s=0,sr=0,as=1,f=6,src=50524cfe,srcvid=view_id(REG,50524cfe,4),insvid=view_id(UNKNOWN,00000000,0),ru=00000000,r=[-1,-1],fs=75,nl=(
}2020-08-25 11:48:04 3054057280 [ERROR] WSREP: failed to open gcomm backend connection: 131: 505b83f7 last prims not consistent (FATAL)
2020-08-25 11:48:04 3054057280 [ERROR] WSREP: gcs/src/gcs_core.cpp:gcs_core_open():209: Failed to open backend connection: -131 (State not recoverable)
2020-08-25 11:48:04 3054057280 [ERROR] WSREP: gcs connect failed: State not recoverable
2020-08-25 11:48:04 3054057280 [ERROR] Aborting
On BB 10.3 , BB 10.4 and BB 10.5 it failed with another output:
stdio.log :
10.3.25 95831888e8a89a4e141e76d51dbfc0701552c824
galera_3nodes.galera_ist_gcache_rollover 'innodb' w2 [ fail ]
Test ended at 2020-08-25 17:51:09
CURRENT_TEST: galera_3nodes.galera_ipv6_mariabackup_section
CURRENT_TEST: galera_3nodes.galera_ipv6_rsync_section
CURRENT_TEST: galera_3nodes.galera_ist_gcache_rollover
Failed to start mysqld.3
worker[2] > Restart - using different config file
worker[2] > Restart - using different config file
worker[2] > Restart - using different config file
- skipping '/dev/shm/var/2/log/galera_3nodes.galera_ist_gcache_rollover-innodb/'
Retrying test galera_3nodes.galera_ist_gcache_rollover, attempt(2/3)...
***Warnings generated in error logs during shutdown after running tests: galera_3nodes.galera_ist_gcache_rollover
WSREP_SST: [ERROR] rsync daemon port '16031' has been taken (20200825 17:50:25.449)
2020-08-25 17:50:25 0 [ERROR] WSREP: Failed to read 'ready <addr>' from: wsrep_sst_rsync --role 'joiner' --address '127.0.0.1:16031' --datadir '/dev/shm/var/2/mysqld.3/data/' --defaults-file '/dev/shm/var/2/my.cnf' --defaults-group-suffix '.3' --parent '32484' --binlog-index 'mysqld-bin.index' --mysqld-args --defaults-group-suffix=.3 --defaults-file=/dev/shm/var/2/my.cnf --log-output=file --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --core-file --loose-debug-sync-timeout=300
2020-08-25 17:50:25 0 [ERROR] WSREP: Process completed with error: wsrep_sst_rsync --role 'joiner' --address '127.0.0.1:16031' --datadir '/dev/shm/var/2/mysqld.3/data/' --defaults-file '/dev/shm/var/2/my.cnf' --defaults-group-suffix '.3' --parent '32484' --binlog-index 'mysqld-bin.index' --mysqld-args --defaults-group-suffix=.3 --defaults-file=/dev/shm/var/2/my.cnf --log-output=file --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --core-file --loose-debug-sync-timeout=300: 16 (Device or resource busy)
2020-08-25 17:50:25 2 [ERROR] WSREP: Failed to prepare for 'rsync' SST. Unrecoverable.
2020-08-25 17:50:25 2 [ERROR] Aborting
Warning: Memory not freed: 1381136
worker[2] mysql-test-run: WARNING: Waited 60 seconds for /dev/shm/var/2/run/mysqld.3.pid to be created, still waiting for 120 seconds...
worker[2] mysql-test-run: WARNING: Waited 120 seconds for /dev/shm/var/2/run/mysqld.3.pid to be created, still waiting for 60 seconds...
worker[2] mysql-test-run: WARNING: Timeout after mysql-test-run waited 180 seconds for the process [mysqld.3 - pid: 979, winpid: 979] to create a pid file.
galera_3nodes.galera_ipv6_rsync_section 'innodb' w2 [ retry-fail ]
Test ended at 2020-08-25 17:54:14
CURRENT_TEST: galera_3nodes.galera_ipv6_mariabackup_section
CURRENT_TEST: galera_3nodes.galera_ipv6_rsync_section
CURRENT_TEST: galera_3nodes.galera_ist_gcache_rollover
CURRENT_TEST: galera_3nodes.galera_ipv6_rsync_section
Failed to start mysqld.3
worker[2] > Restart - using different config file
worker[2] > Restart - using different config file
worker[2] > Restart - using different config file
- skipping '/dev/shm/var/2/log/galera_3nodes.galera_ipv6_rsync_section-innodb/'
Test galera_3nodes.galera_ipv6_rsync_section has failed 2 times, no more retries!
***Warnings generated in error logs during shutdown after running tests: galera_3nodes.galera_ipv6_rsync_section
WSREP_SST: [ERROR] rsync daemon port '16031' has been taken (20200825 17:51:11.334)
2020-08-25 17:51:11 0 [ERROR] WSREP: Failed to read 'ready <addr>' from: wsrep_sst_rsync --role 'joiner' --address '[::1]:16031' --datadir '/dev/shm/var/2/mysqld.3/data/' --defaults-file '/dev/shm/var/2/my.cnf' --defaults-group-suffix '.3' --parent '980' --binlog-index 'mysqld-bin.index' --mysqld-args --defaults-group-suffix=.3 --defaults-file=/dev/shm/var/2/my.cnf --log-output=file --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --bind-address=:: --core-file --loose-debug-sync-timeout=300
2020-08-25 17:51:11 0 [ERROR] WSREP: Process completed with error: wsrep_sst_rsync --role 'joiner' --address '[::1]:16031' --datadir '/dev/shm/var/2/mysqld.3/data/' --defaults-file '/dev/shm/var/2/my.cnf' --defaults-group-suffix '.3' --parent '980' --binlog-index 'mysqld-bin.index' --mysqld-args --defaults-group-suffix=.3 --defaults-file=/dev/shm/var/2/my.cnf --log-output=file --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --bind-address=:: --core-file --loose-debug-sync-timeout=300: 16 (Device or resource busy)
2020-08-25 17:51:11 1 [ERROR] WSREP: Failed to prepare for 'rsync' SST. Unrecoverable.
2020-08-25 17:51:11 1 [ERROR] Aborting
wsrep.mysql_tzinfo_to_sql_symlink 'innodb' w2 [ pass ] 34
worker[2] > Restart [mysqld.1 - pid: 1307, winpid: 1307] - using different config file
galera_3nodes.galera_ist_gcache_rollover 'innodb' w2 [ skipped ] debug_sync is not available
Retrying test galera_3nodes.galera_ist_gcache_rollover, attempt(3/3)...
{"report":{"fcp":692.2000000476837,"ttfb":156.20000004768372,"pageVisibility":"visible","entityId":91132,"key":"jira.project.issue.view-issue","isInitial":true,"threshold":1000,"elementTimings":{},"userDeviceMemory":8,"userDeviceProcessors":64,"apdex":1,"journeyId":"c00036d1-05d6-406e-a829-4b808af13d59","navigationType":0,"readyForUser":773.5999999046326,"redirectCount":0,"resourceLoadedEnd":763.2000000476837,"resourceLoadedStart":161.09999990463257,"resourceTiming":[{"duration":6.1000001430511475,"initiatorType":"link","name":"https://jira.mariadb.org/s/2c21342762a6a02add1c328bed317ffd-CDN/lu2bu7/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/css/_super/batch.css","startTime":161.09999990463257,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":161.09999990463257,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":167.20000004768372,"responseStart":0,"secureConnectionStart":0},{"duration":6.300000190734863,"initiatorType":"link","name":"https://jira.mariadb.org/s/7ebd35e77e471bc30ff0eba799ebc151-CDN/lu2bu7/820016/12ta74/8679b4946efa1a0bb029a3a22206fb5d/_/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":161.39999985694885,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":161.39999985694885,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":167.70000004768372,"responseStart":0,"secureConnectionStart":0},{"duration":64.09999990463257,"initiatorType":"script","name":"https://jira.mariadb.org/s/fbf975c0cce4b1abf04784eeae9ba1f4-CDN/lu2bu7/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/js/_super/batch.js?locale=en","startTime":161.5,"connectEnd":161.5,"connectStart":161.5,"domainLookupEnd":161.5,"domainLookupStart":161.5,"fetchStart":161.5,"redirectEnd":0,"redirectStart":0,"requestStart":161.5,"responseEnd":225.59999990463257,"responseStart":225.59999990463257,"secureConnectionStart":161.5},{"duration":140.70000004768372,"initiatorType":"script","name":"https://jira.mariadb.org/s/099b33461394b8015fc36c0a4b96e19f-CDN/lu2bu7/820016/12ta74/8679b4946efa1a0bb029a3a22206fb5d/_/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":161.59999990463257,"connectEnd":161.59999990463257,"connectStart":161.59999990463257,"domainLookupEnd":161.59999990463257,"domainLookupStart":161.59999990463257,"fetchStart":161.59999990463257,"redirectEnd":0,"redirectStart":0,"requestStart":161.59999990463257,"responseEnd":302.2999999523163,"responseStart":302.2999999523163,"secureConnectionStart":161.59999990463257},{"duration":144.69999980926514,"initiatorType":"script","name":"https://jira.mariadb.org/s/94c15bff32baef80f4096a08aceae8bc-CDN/lu2bu7/820016/12ta74/c92c0caa9a024ae85b0ebdbed7fb4bd7/_/download/contextbatch/js/atl.global,-_super/batch.js?locale=en","startTime":161.70000004768372,"connectEnd":161.70000004768372,"connectStart":161.70000004768372,"domainLookupEnd":161.70000004768372,"domainLookupStart":161.70000004768372,"fetchStart":161.70000004768372,"redirectEnd":0,"redirectStart":0,"requestStart":161.70000004768372,"responseEnd":306.39999985694885,"responseStart":306.39999985694885,"secureConnectionStart":161.70000004768372},{"duration":145.20000004768372,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bu7/820016/12ta74/1.0/_/download/batch/jira.webresources:calendar-en/jira.webresources:calendar-en.js","startTime":161.79999995231628,"connectEnd":161.79999995231628,"connectStart":161.79999995231628,"domainLookupEnd":161.79999995231628,"domainLookupStart":161.79999995231628,"fetchStart":161.79999995231628,"redirectEnd":0,"redirectStart":0,"requestStart":161.79999995231628,"responseEnd":307,"responseStart":307,"secureConnectionStart":161.79999995231628},{"duration":145.70000004768372,"initiatorType":"link","name":"https://jira.mariadb.org/s/b04b06a02d1959df322d9cded3aeecc1-CDN/lu2bu7/820016/12ta74/a2ff6aa845ffc9a1d22fe23d9ee791fc/_/download/contextbatch/css/jira.global.look-and-feel,-_super/batch.css","startTime":162,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":162,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":307.7000000476837,"responseStart":0,"secureConnectionStart":0},{"duration":145.29999995231628,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bu7/820016/12ta74/1.0/_/download/batch/jira.webresources:calendar-localisation-moment/jira.webresources:calendar-localisation-moment.js","startTime":162,"connectEnd":162,"connectStart":162,"domainLookupEnd":162,"domainLookupStart":162,"fetchStart":162,"redirectEnd":0,"redirectStart":0,"requestStart":162,"responseEnd":307.2999999523163,"responseStart":307.2999999523163,"secureConnectionStart":162},{"duration":145.60000014305115,"initiatorType":"script","name":"https://jira.mariadb.org/rest/api/1.0/shortcuts/820016/47140b6e0a9bc2e4913da06536125810/shortcuts.js?context=issuenavigation&context=issueaction","startTime":162.09999990463257,"connectEnd":162.09999990463257,"connectStart":162.09999990463257,"domainLookupEnd":162.09999990463257,"domainLookupStart":162.09999990463257,"fetchStart":162.09999990463257,"redirectEnd":0,"redirectStart":0,"requestStart":162.09999990463257,"responseEnd":307.7000000476837,"responseStart":307.7000000476837,"secureConnectionStart":162.09999990463257},{"duration":145.90000009536743,"initiatorType":"link","name":"https://jira.mariadb.org/s/3ac36323ba5e4eb0af2aa7ac7211b4bb-CDN/lu2bu7/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":162.29999995231628,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":162.29999995231628,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":308.2000000476837,"responseStart":0,"secureConnectionStart":0},{"duration":145.80000019073486,"initiatorType":"script","name":"https://jira.mariadb.org/s/3339d87fa2538a859872f2df449bf8d0-CDN/lu2bu7/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":162.39999985694885,"connectEnd":162.39999985694885,"connectStart":162.39999985694885,"domainLookupEnd":162.39999985694885,"domainLookupStart":162.39999985694885,"fetchStart":162.39999985694885,"redirectEnd":0,"redirectStart":0,"requestStart":162.39999985694885,"responseEnd":308.2000000476837,"responseStart":308.2000000476837,"secureConnectionStart":162.39999985694885},{"duration":411.10000014305115,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bu7/820016/12ta74/1.0/_/download/batch/jira.webresources:bigpipe-js/jira.webresources:bigpipe-js.js","startTime":168.59999990463257,"connectEnd":168.59999990463257,"connectStart":168.59999990463257,"domainLookupEnd":168.59999990463257,"domainLookupStart":168.59999990463257,"fetchStart":168.59999990463257,"redirectEnd":0,"redirectStart":0,"requestStart":168.59999990463257,"responseEnd":579.7000000476837,"responseStart":579.7000000476837,"secureConnectionStart":168.59999990463257},{"duration":420.80000019073486,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bu7/820016/12ta74/1.0/_/download/batch/jira.webresources:bigpipe-init/jira.webresources:bigpipe-init.js","startTime":169.89999985694885,"connectEnd":169.89999985694885,"connectStart":169.89999985694885,"domainLookupEnd":169.89999985694885,"domainLookupStart":169.89999985694885,"fetchStart":169.89999985694885,"redirectEnd":0,"redirectStart":0,"requestStart":169.89999985694885,"responseEnd":590.7000000476837,"responseStart":590.7000000476837,"secureConnectionStart":169.89999985694885},{"duration":144.5,"initiatorType":"xmlhttprequest","name":"https://jira.mariadb.org/rest/webResources/1.0/resources","startTime":441,"connectEnd":441,"connectStart":441,"domainLookupEnd":441,"domainLookupStart":441,"fetchStart":441,"redirectEnd":0,"redirectStart":0,"requestStart":441,"responseEnd":585.5,"responseStart":585.5,"secureConnectionStart":441},{"duration":90.29999995231628,"initiatorType":"script","name":"https://www.google-analytics.com/analytics.js","startTime":671.7999999523163,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":671.7999999523163,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":762.0999999046326,"responseStart":0,"secureConnectionStart":0},{"duration":87.5,"initiatorType":"script","name":"https://jira.mariadb.org/s/d41d8cd98f00b204e9800998ecf8427e-CDN/lu2bu7/820016/12ta74/e65b778d185daf5aee24936755b43da6/_/download/contextbatch/js/browser-metrics-plugin.contrib,-_super,-project.issue.navigator,-jira.view.issue,-atl.general/batch.js?agile_global_admin_condition=true&jag=true&jira.create.linked.issue=true&slack-enabled=true","startTime":675.7000000476837,"connectEnd":675.7000000476837,"connectStart":675.7000000476837,"domainLookupEnd":675.7000000476837,"domainLookupStart":675.7000000476837,"fetchStart":675.7000000476837,"redirectEnd":0,"redirectStart":0,"requestStart":675.7000000476837,"responseEnd":763.2000000476837,"responseStart":763.2000000476837,"secureConnectionStart":675.7000000476837}],"fetchStart":0,"domainLookupStart":0,"domainLookupEnd":0,"connectStart":0,"connectEnd":0,"requestStart":16,"responseStart":156,"responseEnd":170,"domLoading":159,"domInteractive":840,"domContentLoadedEventStart":840,"domContentLoadedEventEnd":882,"domComplete":1641,"loadEventStart":1641,"loadEventEnd":1641,"userAgent":"Mozilla/5.0 AppleWebKit/537.36 (KHTML, like Gecko; compatible; ClaudeBot/1.0; +claudebot@anthropic.com)","marks":[{"name":"bigPipe.sidebar-id.start","time":817.2999999523163},{"name":"bigPipe.sidebar-id.end","time":818.0999999046326},{"name":"bigPipe.activity-panel-pipe-id.start","time":818.2000000476837},{"name":"bigPipe.activity-panel-pipe-id.end","time":820.5999999046326},{"name":"activityTabFullyLoaded","time":896}],"measures":[],"correlationId":"ddd44f1d84472b","effectiveType":"4g","downlink":9.8,"rtt":0,"serverDuration":76,"dbReadsTimeInMs":13,"dbConnsTimeInMs":20,"applicationHash":"9d11dbea5f4be3d4cc21f03a88dd11d8c8687422","experiments":[]}}