Skip to:

  1. Skip to Jira Navigation
  2. Skip to Side Navigation
  3. Skip to Main Content

rocksdb.show_engine: Result content mismatch

Description

$ ./mtr --force rocksdb.show_engine Logging: /var/tmp/portage/dev-db/percona-server-5.7.23.24/work/mysql/mysql-test/mysql-test-run.pl --force rocksdb.show_engine MySQL Version 5.7.23 Too long tmpdir path '/var/tmp/portage/dev-db/percona-server-5.7.23.24/work/percona-server-5.7.23.24_build/mysql-test/var/tmp' creating a shorter one... - using tmpdir: '/tmp/D1VQ9QieKm' Checking supported features... - SSL connections supported Collecting tests... - adding combinations for rocksdb Checking leftover processes... Removing old var directory... Creating var directory '/var/tmp/portage/dev-db/percona-server-5.7.23.24/work/percona-server-5.7.23.24_build/mysql-test/var'... Installing system database... Using parallel: 1 ============================================================================== TEST RESULT TIME (ms) or COMMENT -------------------------------------------------------------------------- worker[1] Using MTR_BUILD_THREAD 300, with reserved ports 13000..13009 rocksdb.show_engine 'write_prepared' [ pass ] 105 rocksdb.show_engine 'write_committed' [ pass ] 68 rocksdb.show_engine 'write_committed-native_partitioning' [ fail ] Test ended at 2018-11-16 17:24:30 CURRENT_TEST: rocksdb.show_engine --- /var/tmp/portage/dev-db/percona-server-5.7.23.24/work/mysql/mysql-test/suite/rocksdb/r-native-partitioning/show_engine.result 2018-10-31 17:10:05.000000000 +0300 +++ /var/tmp/portage/dev-db/percona-server-5.7.23.24/work/percona-server-5.7.23.24_build/mysql-test/var/log/show_engine.reject 2018-11-16 19:24:30.096608963 +0300 @@ -136,6 +136,7 @@ __system__ TABLE_FACTORY::CACHE_INDEX_AND_FILTER_BLOCKS # __system__ TABLE_FACTORY::CACHE_INDEX_AND_FILTER_BLOCKS_WITH_HIGH_PRIORITY # __system__ TABLE_FACTORY::PIN_L0_FILTER_AND_INDEX_BLOCKS_IN_CACHE # +__system__ TABLE_FACTORY::PIN_TOP_LEVEL_INDEX_AND_FILTER # __system__ TABLE_FACTORY::INDEX_TYPE # __system__ TABLE_FACTORY::HASH_INDEX_ALLOW_COLLISION # __system__ TABLE_FACTORY::CHECKSUM # @@ -208,6 +209,7 @@ cf_t1 TABLE_FACTORY::CACHE_INDEX_AND_FILTER_BLOCKS # cf_t1 TABLE_FACTORY::CACHE_INDEX_AND_FILTER_BLOCKS_WITH_HIGH_PRIORITY # cf_t1 TABLE_FACTORY::PIN_L0_FILTER_AND_INDEX_BLOCKS_IN_CACHE # +cf_t1 TABLE_FACTORY::PIN_TOP_LEVEL_INDEX_AND_FILTER # cf_t1 TABLE_FACTORY::INDEX_TYPE # cf_t1 TABLE_FACTORY::HASH_INDEX_ALLOW_COLLISION # cf_t1 TABLE_FACTORY::CHECKSUM # @@ -280,6 +282,7 @@ default TABLE_FACTORY::CACHE_INDEX_AND_FILTER_BLOCKS # default TABLE_FACTORY::CACHE_INDEX_AND_FILTER_BLOCKS_WITH_HIGH_PRIORITY # default TABLE_FACTORY::PIN_L0_FILTER_AND_INDEX_BLOCKS_IN_CACHE # +default TABLE_FACTORY::PIN_TOP_LEVEL_INDEX_AND_FILTER # default TABLE_FACTORY::INDEX_TYPE # default TABLE_FACTORY::HASH_INDEX_ALLOW_COLLISION # default TABLE_FACTORY::CHECKSUM # @@ -352,6 +355,7 @@ rev:cf_t2 TABLE_FACTORY::CACHE_INDEX_AND_FILTER_BLOCKS # rev:cf_t2 TABLE_FACTORY::CACHE_INDEX_AND_FILTER_BLOCKS_WITH_HIGH_PRIORITY # rev:cf_t2 TABLE_FACTORY::PIN_L0_FILTER_AND_INDEX_BLOCKS_IN_CACHE # +rev:cf_t2 TABLE_FACTORY::PIN_TOP_LEVEL_INDEX_AND_FILTER # rev:cf_t2 TABLE_FACTORY::INDEX_TYPE # rev:cf_t2 TABLE_FACTORY::HASH_INDEX_ALLOW_COLLISION # rev:cf_t2 TABLE_FACTORY::CHECKSUM # mysqltest: Result content mismatch safe_process[24682]: Child process: 24683, exit: 1 Server [mysqld.1 - pid: 24648, winpid: 24648] log: Server log from this test: ----------SERVER LOG START----------- 2018-11-16T16:24:29.658742Z 0 [Warning] Insecure configuration for --secure-file-priv: Data directory is accessible through --secure-file-priv. Consider choosing a different directory. 2018-11-16T16:24:29.658847Z 0 [Warning] Insecure configuration for --secure-file-priv: Location is accessible to all OS users. Consider choosing a different directory. 2018-11-16T16:24:29.658871Z 0 [Note] /var/tmp/portage/dev-db/percona-server-5.7.23.24/work/percona-server-5.7.23.24_build/sql/mysqld (mysqld 5.7.23-24-log) starting as process 24649 ... 2018-11-16T16:24:29.661337Z 0 [Note] InnoDB: PUNCH HOLE support available 2018-11-16T16:24:29.661355Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2018-11-16T16:24:29.661361Z 0 [Note] InnoDB: Uses event mutexes 2018-11-16T16:24:29.661366Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier 2018-11-16T16:24:29.661371Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.11 2018-11-16T16:24:29.661376Z 0 [Note] InnoDB: Using Linux native AIO 2018-11-16T16:24:29.661593Z 0 [Note] InnoDB: Number of pools: 1 2018-11-16T16:24:29.661697Z 0 [Note] InnoDB: Using CPU crc32 instructions 2018-11-16T16:24:29.663159Z 0 [Note] InnoDB: Initializing buffer pool, total size = 24M, instances = 1, chunk size = 24M 2018-11-16T16:24:29.664109Z 0 [Note] InnoDB: Completed initialization of buffer pool 2018-11-16T16:24:29.664925Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority(). 2018-11-16T16:24:29.677146Z 0 [Note] InnoDB: Crash recovery did not find the parallel doublewrite buffer at /var/tmp/portage/dev-db/percona-server-5.7.23.24/work/percona-server-5.7.23.24_build/mysql-test/var/mysqld.1/data/xb_doublewrite 2018-11-16T16:24:29.677624Z 0 [Note] InnoDB: Highest supported file format is Barracuda. 2018-11-16T16:24:29.691429Z 0 [Note] InnoDB: Created parallel doublewrite buffer at /var/tmp/portage/dev-db/percona-server-5.7.23.24/work/percona-server-5.7.23.24_build/mysql-test/var/mysqld.1/data/xb_doublewrite, size 3932160 bytes 2018-11-16T16:24:29.695124Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables 2018-11-16T16:24:29.695174Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ... 2018-11-16T16:24:29.726792Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB. 2018-11-16T16:24:29.727525Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active. 2018-11-16T16:24:29.727536Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active. 2018-11-16T16:24:29.728122Z 0 [Note] InnoDB: Waiting for purge to start 2018-11-16T16:24:29.779017Z 0 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.7.23-24 started; log sequence number 13555412018-11-16T16:24:29.779412Z 0 [Note] InnoDB: Loading buffer pool(s) from /var/tmp/portage/dev-db/percona-server-5.7.23.24/work/percona-server-5.7.23.24_build/mysql-test/var/mysqld.1/data/ib_buffer_pool 2018-11-16T16:24:29.779862Z 0 [Note] Plugin 'FEDERATED' is disabled. 2018-11-16T16:24:29.782332Z 0 [Note] InnoDB: Buffer pool(s) load completed at 181116 19:24:29 2018-11-16T16:24:29.804448Z 0 [Note] RocksDB: Got ENOENT when listing column families 2018-11-16T16:24:29.804468Z 0 [Note] RocksDB: assuming that we're creating a new database 2018-11-16T16:24:29.804759Z 0 [Note] RocksDB: Column Families at start: 2018-11-16T16:24:29.804799Z 0 [Note] cf=default 2018-11-16T16:24:29.804822Z 0 [Note] write_buffer_size=67108864 2018-11-16T16:24:29.804828Z 0 [Note] target_file_size_base=67108864 2018-11-16T16:24:29.812627Z 0 [Note] RocksDB: creating a column family __system__ 2018-11-16T16:24:29.812642Z 0 [Note] write_buffer_size=67108864 2018-11-16T16:24:29.812647Z 0 [Note] target_file_size_base=67108864 2018-11-16T16:24:29.847562Z 0 [Note] RocksDB: Table_store: loaded DDL data for 0 tables 2018-11-16T16:24:29.851785Z 0 [Note] RocksDB instance opened 2018-11-16T16:24:29.851867Z 0 [Warning] unknown variable 'loose-mysqlx-port=13009' 2018-11-16T16:24:29.851875Z 0 [Warning] unknown variable 'loose-mysqlx-socket=/tmp/D1VQ9QieKm/mysqlx.1.sock' 2018-11-16T16:24:29.851879Z 0 [Warning] unknown variable 'loose-debug-sync-timeout=600' 2018-11-16T16:24:29.853548Z 0 [Note] Salting uuid generator variables, current_pid: 24649, server_start_time: 1542385469, bytes_sent: 0, 2018-11-16T16:24:29.853596Z 0 [Note] Generated uuid: '17e0dc0e-e9bc-11e8-a1ba-000c29545adf', server_start_time: 6938076702483369862, bytes_sent: 94145353378048 2018-11-16T16:24:29.853612Z 0 [Warning] No existing UUID has been found, so we assume that this is the first time that this server has been started. Generating a new UUID: 17e0dc0e-e9bc-11e8-a1ba-000c29545adf. 2018-11-16T16:24:29.855711Z 0 [Note] Skipping generation of SSL certificates as options related to SSL are specified. 2018-11-16T16:24:29.856262Z 0 [Warning] CA certificate /var/tmp/portage/dev-db/percona-server-5.7.23.24/work/mysql/mysql-test/std_data/cacert.pem is self signed. 2018-11-16T16:24:29.856291Z 0 [Note] Skipping generation of RSA key pair as --sha256_password_auto_generate_rsa_keys is set to OFF. 2018-11-16T16:24:29.856306Z 0 [Note] RSA private key file not found: /var/tmp/portage/dev-db/percona-server-5.7.23.24/work/percona-server-5.7.23.24_build/mysql-test/var/mysqld.1/data//private_key.pem. Some authentication plugins will not work. 2018-11-16T16:24:29.856317Z 0 [Note] RSA public key file not found: /var/tmp/portage/dev-db/percona-server-5.7.23.24/work/percona-server-5.7.23.24_build/mysql-test/var/mysqld.1/data//public_key.pem. Some authentication plugins will not work. 2018-11-16T16:24:29.856323Z 0 [Note] Server hostname (bind-address): '*'; port: 13001 2018-11-16T16:24:29.856345Z 0 [Note] IPv6 is available. 2018-11-16T16:24:29.856354Z 0 [Note] - '::' resolves to '::'; 2018-11-16T16:24:29.856409Z 0 [Note] Server socket created on IP: '::'. 2018-11-16T16:24:29.857095Z 0 [Warning] Insecure configuration for --pid-file: Location '/var/tmp' in the path is accessible to all OS users. Consider choosing a different directory. 2018-11-16T16:24:29.866660Z 0 [Note] Event Scheduler: Loaded 0 events 2018-11-16T16:24:29.866963Z 0 [Note] /var/tmp/portage/dev-db/percona-server-5.7.23.24/work/percona-server-5.7.23.24_build/sql/mysqld: ready for connections. Version: '5.7.23-24-log' socket: '/tmp/D1VQ9QieKm/mysqld.1.sock' port: 13001 Gentoo Linux percona-server-5.7.23.24 2018-11-16T16:24:30.033551Z 3 [Note] RocksDB: creating a column family cf_t1 2018-11-16T16:24:30.033566Z 3 [Note] write_buffer_size=67108864 2018-11-16T16:24:30.033570Z 3 [Note] target_file_size_base=67108864 2018-11-16T16:24:30.039089Z 3 [Note] RocksDB: creating a column family rev:cf_t2 2018-11-16T16:24:30.039102Z 3 [Note] write_buffer_size=67108864 2018-11-16T16:24:30.039106Z 3 [Note] target_file_size_base=67108864 2018-11-16T16:24:30.057294Z 3 [Note] RocksDB: Manual memtable flush. 2018-11-16T16:24:30.064972Z 3 [Note] RocksDB: Manual compaction of column family: cf_t1 ----------SERVER LOG END-------------

Environment

None

Smart Checklist

Activity

Show:

George Lorch November 21, 2018 at 5:56 PM
Edited

Lalit Choudhary November 20, 2018 at 3:26 PM

Hi Thomas,

Thank you for the report.

Validated as described.

Done

Details

Assignee

Reporter

Labels

Time tracking

30m logged

Components

Fix versions

Affects versions

Priority

Smart Checklist

Created November 16, 2018 at 4:31 PM
Updated March 6, 2024 at 12:41 PM
Resolved November 21, 2018 at 7:29 PM

Flag notifications