The test fails sporadically.
http://buildbot.askmonty.org/buildbot/builders/p8-rhel6-bintar-debug/builds/1221/steps/test/logs/stdio
parts.partition_debug_innodb 'xtradb' w1 [ fail ]
|
Test ended at 2016-01-03 07:22:08
|
|
CURRENT_TEST: parts.partition_debug_innodb
|
mysqltest: In included file "./include/wait_until_connected_again.inc":
|
included from ./suite/parts/inc/partition_crash.inc at line 22:
|
included from ./suite/parts/inc/partition_crash_t2.inc at line 9:
|
included from ./suite/parts/inc/partition_crash_exchange.inc at line 10:
|
included from /home/buildbot/maria-slave/power8-vlp01-bintar-debug/build/mysql-test/suite/parts/t/partition_debug_innodb.test at line 90:
|
At line 26: Server failed to restart
|
|
The result from queries just before the failure was:
|
< snip >
|
11 Original from partition p1
|
12 Original from partition p1
|
13 Original from partition p1
|
14 Original from partition p1
|
2 Original from partition p0
|
21 Original from partition p1
|
22 Original from partition p1
|
23 Original from partition p1
|
24 Original from partition p1
|
3 Original from partition p0
|
4 Original from partition p0
|
ALTER TABLE t1 EXCHANGE PARTITION p0 WITH TABLE t2;
|
ERROR HY000: Lost connection to MySQL server during query
|
# State after crash (before recovery)
|
t1#P#p0.ibd
|
t1#P#p1.ibd
|
t1.frm
|
t1.par
|
t2.frm
|
t2.ibd
|
Server log: http://buildbot.askmonty.org/buildbot/builders/p8-rhel6-bintar-debug/builds/1221/steps/test/logs/mysqld.1.err.1
From the log, it looks like at some point recovery just stopped:
...
|
160103 7:13:46 [Note] /home/buildbot/maria-slave/power8-vlp01-bintar-debug/build/sql/mysqld: ready for connections.
|
Version: '10.0.23-MariaDB-debug-log' socket: '/home/buildbot/maria-slave/power8-vlp01-bintar-debug/build/mysql-test/var/tmp/1/mysqld.1.sock' port: 16040 Source distribution
|
SIGKILL myself
|
160103 7:13:46 [Note] /home/buildbot/maria-slave/power8-vlp01-bintar-debug/build/sql/mysqld (mysqld 10.0.23-MariaDB-debug-log) starting as process 4219 ...
|
160103 7:13:46 [Note] InnoDB: Using mutexes to ref count buffer pool pages
|
2016-01-03 07:13:46 fff95416ff0 InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
|
2016-01-03 07:13:46 fff95416ff0 InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!!
|
160103 7:13:46 [Note] InnoDB: The InnoDB memory heap is disabled
|
160103 7:13:46 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
|
160103 7:13:46 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
|
160103 7:13:46 [Note] InnoDB: Compressed tables use zlib 1.2.6
|
160103 7:13:46 [Note] InnoDB: Using Linux native AIO
|
160103 7:13:46 [Note] InnoDB: Not using CPU crc32 instructions
|
160103 7:13:46 [Note] InnoDB: Initializing buffer pool, size = 8.0M
|
160103 7:13:46 [Note] InnoDB: Completed initialization of buffer pool
|
160103 7:13:46 [Note] InnoDB: Highest supported file format is Barracuda.
|
160103 7:13:46 [Note] InnoDB: Log scan progressed past the checkpoint lsn 1629973
|
160103 7:13:46 [Note] InnoDB: Database was not shutdown normally!
|
160103 7:13:46 [Note] InnoDB: Starting crash recovery.
|
160103 7:13:46 [Note] InnoDB: Reading tablespace information from the .ibd files...
|
160103 7:13:46 [Note] InnoDB: Restoring possible half-written data pages
|
160103 7:13:46 [Note] InnoDB: from the doublewrite buffer...
|
InnoDB: Doing recovery: scanned up to log sequence number 2993928
|
160103 7:13:47 [Note] InnoDB: Starting an apply batch of log records to the database...
|
InnoDB: Progress in percent: 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 32 33 34 35 36 37 38 39 40 41 42 43 44 45 46 47 48 49 50 51 52 53 54 55 56 57 58 59 60 61 62 63 64 65 66 67 68 69 70 71 72 73 74 75 76 77 78 79 80 81 82 83 84 85 86 87 88 89 90 91 92 93 94 95 96 97 98 99
|
InnoDB: Apply batch completed
|
CURRENT_TEST: parts.partition_repair_myisam
|
160103 7:22:09 [Note] /home/buildbot/maria-slave/power8-vlp01-bintar-debug/build/sql/mysqld (mysqld 10.0.23-MariaDB-debug-log) starting as process 16838 ...
|
...
|
- blocks
-
MDEV-7069
Fix buildbot failures in main server trees
-
-
Stalled
{"report":{"fcp":972.3999996185303,"ttfb":278.5,"pageVisibility":"visible","entityId":55006,"key":"jira.project.issue.view-issue","isInitial":true,"threshold":1000,"elementTimings":{},"userDeviceMemory":8,"userDeviceProcessors":64,"apdex":0.5,"journeyId":"e9afeccb-4c9c-4707-9ac1-ad29a2c8b545","navigationType":0,"readyForUser":1096.0999994277954,"redirectCount":0,"resourceLoadedEnd":687.1999998092651,"resourceLoadedStart":283.8999996185303,"resourceTiming":[{"duration":65.19999980926514,"initiatorType":"link","name":"https://jira.mariadb.org/s/2c21342762a6a02add1c328bed317ffd-CDN/lu2cib/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/css/_super/batch.css","startTime":283.8999996185303,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":283.8999996185303,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":349.0999994277954,"responseStart":0,"secureConnectionStart":0},{"duration":65.5,"initiatorType":"link","name":"https://jira.mariadb.org/s/7ebd35e77e471bc30ff0eba799ebc151-CDN/lu2cib/820016/12ta74/2bf333562ca6724060a9d5f1535471f6/_/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":284.19999980926514,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":284.19999980926514,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":349.69999980926514,"responseStart":0,"secureConnectionStart":0},{"duration":294.8999996185303,"initiatorType":"script","name":"https://jira.mariadb.org/s/0917945aaa57108d00c5076fea35e069-CDN/lu2cib/820016/12ta74/0a8bac35585be7fc6c9cc5a0464cd4cf/_/download/contextbatch/js/_super/batch.js?locale=en","startTime":284.30000019073486,"connectEnd":284.30000019073486,"connectStart":284.30000019073486,"domainLookupEnd":284.30000019073486,"domainLookupStart":284.30000019073486,"fetchStart":284.30000019073486,"redirectEnd":0,"redirectStart":0,"requestStart":351.5,"responseEnd":579.1999998092651,"responseStart":384.0999994277954,"secureConnectionStart":284.30000019073486},{"duration":402.69999980926514,"initiatorType":"script","name":"https://jira.mariadb.org/s/2d8175ec2fa4c816e8023260bd8c1786-CDN/lu2cib/820016/12ta74/2bf333562ca6724060a9d5f1535471f6/_/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":284.5,"connectEnd":284.5,"connectStart":284.5,"domainLookupEnd":284.5,"domainLookupStart":284.5,"fetchStart":284.5,"redirectEnd":0,"redirectStart":0,"requestStart":353.5999994277954,"responseEnd":687.1999998092651,"responseStart":417,"secureConnectionStart":284.5},{"duration":106.69999980926514,"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":284.69999980926514,"connectEnd":284.69999980926514,"connectStart":284.69999980926514,"domainLookupEnd":284.69999980926514,"domainLookupStart":284.69999980926514,"fetchStart":284.69999980926514,"redirectEnd":0,"redirectStart":0,"requestStart":354.5,"responseEnd":391.3999996185303,"responseStart":390,"secureConnectionStart":284.69999980926514},{"duration":109.69999980926514,"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":284.8999996185303,"connectEnd":284.8999996185303,"connectStart":284.8999996185303,"domainLookupEnd":284.8999996185303,"domainLookupStart":284.8999996185303,"fetchStart":284.8999996185303,"redirectEnd":0,"redirectStart":0,"requestStart":354.80000019073486,"responseEnd":394.5999994277954,"responseStart":393.3999996185303,"secureConnectionStart":284.8999996185303},{"duration":111.60000038146973,"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":285.0999994277954,"connectEnd":285.0999994277954,"connectStart":285.0999994277954,"domainLookupEnd":285.0999994277954,"domainLookupStart":285.0999994277954,"fetchStart":285.0999994277954,"redirectEnd":0,"redirectStart":0,"requestStart":355.80000019073486,"responseEnd":396.69999980926514,"responseStart":394.80000019073486,"secureConnectionStart":285.0999994277954},{"duration":69.89999961853027,"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":285.30000019073486,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":285.30000019073486,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":355.19999980926514,"responseStart":0,"secureConnectionStart":0},{"duration":176.80000019073486,"initiatorType":"script","name":"https://jira.mariadb.org/rest/api/1.0/shortcuts/820016/47140b6e0a9bc2e4913da06536125810/shortcuts.js?context=issuenavigation&context=issueaction","startTime":285.3999996185303,"connectEnd":285.3999996185303,"connectStart":285.3999996185303,"domainLookupEnd":285.3999996185303,"domainLookupStart":285.3999996185303,"fetchStart":285.3999996185303,"redirectEnd":0,"redirectStart":0,"requestStart":358.0999994277954,"responseEnd":462.19999980926514,"responseStart":460.3999996185303,"secureConnectionStart":285.3999996185303},{"duration":70.40000057220459,"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":285.5999994277954,"connectEnd":0,"connectStart":0,"domainLookupEnd":0,"domainLookupStart":0,"fetchStart":285.5999994277954,"redirectEnd":0,"redirectStart":0,"requestStart":0,"responseEnd":356,"responseStart":0,"secureConnectionStart":0},{"duration":111.19999980926514,"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":285.80000019073486,"connectEnd":285.80000019073486,"connectStart":285.80000019073486,"domainLookupEnd":285.80000019073486,"domainLookupStart":285.80000019073486,"fetchStart":285.80000019073486,"redirectEnd":0,"redirectStart":0,"requestStart":359.19999980926514,"responseEnd":397,"responseStart":395.3999996185303,"secureConnectionStart":285.80000019073486},{"duration":387.5,"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":291.19999980926514,"connectEnd":291.19999980926514,"connectStart":291.19999980926514,"domainLookupEnd":291.19999980926514,"domainLookupStart":291.19999980926514,"fetchStart":291.19999980926514,"redirectEnd":0,"redirectStart":0,"requestStart":520.8000001907349,"responseEnd":678.6999998092651,"responseStart":672.5,"secureConnectionStart":291.19999980926514},{"duration":390.5,"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":291.30000019073486,"connectEnd":291.30000019073486,"connectStart":291.30000019073486,"domainLookupEnd":291.30000019073486,"domainLookupStart":291.30000019073486,"fetchStart":291.30000019073486,"redirectEnd":0,"redirectStart":0,"requestStart":618.6999998092651,"responseEnd":681.8000001907349,"responseStart":677,"secureConnectionStart":291.30000019073486},{"duration":104.10000038146973,"initiatorType":"xmlhttprequest","name":"https://jira.mariadb.org/rest/webResources/1.0/resources","startTime":710.8999996185303,"connectEnd":710.8999996185303,"connectStart":710.8999996185303,"domainLookupEnd":710.8999996185303,"domainLookupStart":710.8999996185303,"fetchStart":710.8999996185303,"redirectEnd":0,"redirectStart":0,"requestStart":782.1999998092651,"responseEnd":815,"responseStart":813.5999994277954,"secureConnectionStart":710.8999996185303}],"fetchStart":0,"domainLookupStart":0,"domainLookupEnd":0,"connectStart":0,"connectEnd":0,"requestStart":97,"responseStart":279,"responseEnd":287,"domLoading":282,"domInteractive":1162,"domContentLoadedEventStart":1162,"domContentLoadedEventEnd":1210,"domComplete":1795,"loadEventStart":1795,"loadEventEnd":1796,"userAgent":"Mozilla/5.0 AppleWebKit/537.36 (KHTML, like Gecko; compatible; ClaudeBot/1.0; +claudebot@anthropic.com)","marks":[{"name":"bigPipe.sidebar-id.start","time":1139.5999994277954},{"name":"bigPipe.sidebar-id.end","time":1140.3000001907349},{"name":"bigPipe.activity-panel-pipe-id.start","time":1140.5},{"name":"bigPipe.activity-panel-pipe-id.end","time":1142},{"name":"activityTabFullyLoaded","time":1229}],"measures":[],"correlationId":"67a6b8d9e7529","effectiveType":"4g","downlink":10,"rtt":0,"serverDuration":102,"dbReadsTimeInMs":12,"dbConnsTimeInMs":20,"applicationHash":"9d11dbea5f4be3d4cc21f03a88dd11d8c8687422","experiments":[]}}
Bug
Repeated on Linux (my laptop) with ./mysql-test-run --vardir=/dev/shm parts.partition_debug_innodb,xtradb --repeat=20 , but reason could be too small buffer pool setting.
2016-02-16 09:08:42 7f04ff0a1740 InnoDB: Warning: difficult to find free blocks inInnoDB: the buffer pool (5002 search iterations)!InnoDB: 5002 failed attempts to flush a page! ConsiderInnoDB: increasing the buffer pool size.InnoDB: It is also possible that in your Unix versionInnoDB: fsync is very slow, or completely frozen insideInnoDB: the OS kernel. Then upgrading to a newer versionInnoDB: of your operating system may help. Look at theInnoDB: number of fsyncs in diagnostic info below.InnoDB: Pending flushes (fsync) log: 0; buffer pool: 0InnoDB: 666 OS file reads, 72 OS file writes, 72 OS fsyncsInnoDB: Starting InnoDB Monitor to print furtherInnoDB: diagnostics to the standard output.