Uploaded image for project: 'Percona Server'
  1. Percona Server
  2. PS-5033

rocksdb.show_engine: Result content mismatch

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Done
    • Priority: Medium
    • Resolution: Fixed
    • Affects Version/s: Not 5.6, Not 8.0, 5.7.23-24
    • Fix Version/s: 5.7.24-26
    • Component/s: MyRocks
    • Labels:

      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-------------
      

        Attachments

          Activity

            People

            • Assignee:
              george.lorch George Lorch
              Reporter:
              Whissi Thomas Deutschmann
            • Votes:
              0 Vote for this issue
              Watchers:
              3 Start watching this issue

              Dates

              • Created:
                Updated:
                Resolved:

                Time Tracking

                Estimated:
                Original Estimate - Not Specified
                Not Specified
                Remaining:
                Remaining Estimate - Not Specified
                Not Specified
                Logged:
                Time Spent - 30 minutes
                30m