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

test innodb.innodb_scrub_background fails: unknown variable 'innodb-encrypt-tables=KEYRING_ON'

    Details

    • Type: Bug
    • Status: Done
    • Priority: Medium
    • Resolution: Fixed
    • Affects Version/s: None
    • Fix Version/s: None
    • Component/s: None
    • Labels:
      None

      Description

      ==============================================================================
                        TEST NAME                       RESULT  TIME (ms) COMMENT
      ------------------------------------------------------------------------------
      worker[1] mysql-test-run: WARNING: Process [mysqld.1 - pid: 105246, winpid: 105246, exit: 256] died after mysql-test-run waited 3.1 seconds for /var/tmp/portage/dev-db/percona-server-8.0.16.7/work/percona-server-8.0.16.7_build/mysql-test/var/run/mysqld.1.pid to be created.
      [100%] innodb.innodb_scrub_background            [ fail ]
              Test ended at 2019-10-30 12:11:47
      
      
      
      Server log is:
      2019-10-30T12:11:45.005141Z 0 [Warning] [MY-010099] [Server] Insecure configuration for --secure-file-priv: Data directory is accessible through --secure-file-priv. Consider choosing a different directory.
      2019-10-30T12:11:45.005152Z 0 [Warning] [MY-010101] [Server] Insecure configuration for --secure-file-priv: Location is accessible to all OS users. Consider choosing a different directory.
      2019-10-30T12:11:45.005180Z 0 [Note] [MY-010949] [Server] Basedir set to /var/tmp/portage/dev-db/percona-server-8.0.16.7/work/percona-server-8.0.16.7_build/.
      2019-10-30T12:11:45.005190Z 0 [System] [MY-010116] [Server] /var/tmp/portage/dev-db/percona-server-8.0.16.7/work/percona-server-8.0.16.7_build/runtime_output_directory/mysqld (mysqld 8.0.16-7) starting as process 105247
      2019-10-30T12:11:45.038438Z 0 [Note] [MY-012366] [InnoDB] Using Linux native AIO
      2019-10-30T12:11:45.038614Z 0 [Note] [MY-010747] [Server] Plugin 'FEDERATED' is disabled.
      2019-10-30T12:11:45.039855Z 1 [Note] [MY-012932] [InnoDB] PUNCH HOLE support available
      2019-10-30T12:11:45.039890Z 1 [Note] [MY-012943] [InnoDB] Mutexes and rw_locks use GCC atomic builtins
      2019-10-30T12:11:45.039912Z 1 [Note] [MY-012944] [InnoDB] Uses event mutexes
      2019-10-30T12:11:45.039931Z 1 [Note] [MY-012945] [InnoDB] GCC builtin __atomic_thread_fence() is used for memory barrier
      2019-10-30T12:11:45.039960Z 1 [Note] [MY-012948] [InnoDB] Compressed tables use zlib 1.2.11
      2019-10-30T12:11:45.040255Z 1 [Note] [MY-013251] [InnoDB] Number of pools: 1
      2019-10-30T12:11:45.040387Z 1 [Note] [MY-012951] [InnoDB] Using CPU crc32 instructions
      2019-10-30T12:11:45.040906Z 1 [Note] [MY-012203] [InnoDB] Directories to scan './;./;./'
      2019-10-30T12:11:45.040978Z 1 [Note] [MY-012204] [InnoDB] Scanning './'
      2019-10-30T12:11:45.041963Z 1 [Note] [MY-012208] [InnoDB] Completed space ID check of 6 files.
      2019-10-30T12:11:45.043106Z 1 [Note] [MY-012955] [InnoDB] Initializing buffer pool, total size = 24.000000M, instances = 1, chunk size =24.000000M
      2019-10-30T12:11:45.044320Z 1 [Note] [MY-012957] [InnoDB] Completed initialization of buffer pool
      2019-10-30T12:11:45.045014Z 0 [Note] [MY-011952] [InnoDB] If the mysqld execution user is authorized, page cleaner and LRU manager thread priority can be changed. See the man page of setpriority().
      2019-10-30T12:11:45.056408Z 1 [Note] [MY-011825] [InnoDB] Crash recovery did not find the parallel doublewrite buffer at /var/tmp/portage/dev-db/percona-server-8.0.16.7/work/percona-server-8.0.16.7_build/mysql-test/var/mysqld.1/data/xb_doublewrite
      2019-10-30T12:11:45.121740Z 1 [Note] [MY-013086] [InnoDB] Starting to parse redo log at lsn = 19343908, whereas checkpoint_lsn = 19344010
      2019-10-30T12:11:45.132944Z 1 [Note] [MY-013083] [InnoDB] Log background threads are being started...
      2019-10-30T12:11:45.152515Z 1 [Note] [MY-011825] [InnoDB] Created parallel doublewrite buffer at /var/tmp/portage/dev-db/percona-server-8.0.16.7/work/percona-server-8.0.16.7_build/mysql-test/var/mysqld.1/data/xb_doublewrite, size 3932160 bytes
      2019-10-30T12:11:45.152559Z 1 [Note] [MY-012532] [InnoDB] Applying a batch of 0 redo log records ...
      2019-10-30T12:11:45.152579Z 1 [Note] [MY-012535] [InnoDB] Apply batch completed!
      2019-10-30T12:11:45.152728Z 1 [Note] [MY-013252] [InnoDB] Using undo tablespace './undo_001'.
      2019-10-30T12:11:45.152907Z 1 [Note] [MY-013252] [InnoDB] Using undo tablespace './undo_002'.
      2019-10-30T12:11:45.155650Z 1 [Note] [MY-012910] [InnoDB] Opened 2 existing undo tablespaces.
      2019-10-30T12:11:45.163145Z 1 [Note] [MY-012923] [InnoDB] Creating shared tablespace for temporary tables
      2019-10-30T12:11:45.163216Z 1 [Note] [MY-012265] [InnoDB] Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
      2019-10-30T12:11:45.177440Z 1 [Note] [MY-012266] [InnoDB] File './ibtmp1' size is now 12 MB.
      2019-10-30T12:11:45.177633Z 1 [Note] [MY-011825] [InnoDB] Scanning temp tablespace dir:'./#innodb_temp/'
      2019-10-30T12:11:45.273211Z 1 [Note] [MY-013018] [InnoDB] Created 128 and tracked 128 new rollback segment(s) in the temporary tablespace. 128 are now active.
      2019-10-30T12:11:45.273365Z 1 [Note] [MY-012976] [InnoDB] Percona XtraDB (http://www.percona.com) 8.0.16-7 started; log sequence number 19344010
      2019-10-30T12:11:45.277278Z 1 [Note] [MY-011089] [Server] Data dictionary restarting version '80016'.
      2019-10-30T12:11:45.329592Z 1 [Note] [MY-012357] [InnoDB] Reading DD tablespace files
      2019-10-30T12:11:45.330202Z 1 [Note] [MY-012356] [InnoDB] Validated 8/8  tablespaces
      2019-10-30T12:11:45.330686Z 0 [Note] [MY-011946] [InnoDB] Loading buffer pool(s) from /var/tmp/portage/dev-db/percona-server-8.0.16.7/work/percona-server-8.0.16.7_build/mysql-test/var/mysqld.1/data/ib_buffer_pool
      2019-10-30T12:11:45.333533Z 0 [Note] [MY-011946] [InnoDB] Buffer pool(s) load completed at 191030 15:11:45
      2019-10-30T12:11:45.346508Z 1 [Note] [MY-010006] [Server] Using data dictionary with version '80016'.
      2019-10-30T12:11:45.412041Z 0 [Note] [MY-010902] [Server] Thread priority attribute setting in Resource Group SQL shall be ignored due to unsupported platform or insufficient privilege.
      2019-10-30T12:11:45.430992Z 0 [Note] [MY-012487] [InnoDB] DDL log recovery : begin
      2019-10-30T12:11:45.431041Z 0 [Note] [MY-012488] [InnoDB] DDL log recovery : end
      2019-10-30T12:11:45.431168Z 0 [Note] [MY-012922] [InnoDB] Waiting for purge to start
      2019-10-30T12:11:45.481389Z 0 [Warning] [MY-010075] [Server] 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: 70f649e7-fb0e-11e9-8e75-7085c2a9c0a3.
      2019-10-30T12:11:45.504717Z 0 [Note] [MY-010303] [Server] Skipping generation of SSL certificates as options related to SSL are specified.
      2019-10-30T12:11:45.505366Z 0 [Warning] [MY-010068] [Server] CA certificate /var/tmp/portage/dev-db/percona-server-8.0.16.7/work/mysql/mysql-test/std_data/cacert.pem is self signed.
      2019-10-30T12:11:45.505388Z 0 [Note] [MY-010310] [Server] Skipping generation of RSA key pair as --sha256_password_auto_generate_rsa_keys is set to OFF.
      2019-10-30T12:11:45.505397Z 0 [Note] [MY-010310] [Server] Skipping generation of RSA key pair as --caching_sha2_password_auto_generate_rsa_keys is set to OFF.
      2019-10-30T12:11:45.505417Z 0 [Warning] [MY-010284] [Server] RSA private key file not found: /var/tmp/portage/dev-db/percona-server-8.0.16.7/work/percona-server-8.0.16.7_build/mysql-test/var/mysqld.1/data//private_key.pem. Some authentication plugins will not work.
      2019-10-30T12:11:45.505431Z 0 [Warning] [MY-010284] [Server] RSA public key file not found: /var/tmp/portage/dev-db/percona-server-8.0.16.7/work/percona-server-8.0.16.7_build/mysql-test/var/mysqld.1/data//public_key.pem. Some authentication plugins will not work.
      2019-10-30T12:11:45.505518Z 0 [Note] [MY-010252] [Server] Server hostname (bind-address): '*'; port: 13000
      2019-10-30T12:11:45.505545Z 0 [Note] [MY-010253] [Server] IPv6 is available.
      2019-10-30T12:11:45.505555Z 0 [Note] [MY-010264] [Server]   - '::' resolves to '::';
      2019-10-30T12:11:45.505601Z 0 [Note] [MY-010251] [Server] Server socket created on IP: '::'.
      2019-10-30T12:11:45.508865Z 0 [Warning] [MY-011810] [Server] Insecure configuration for --pid-file: Location '/var/tmp' in the path is accessible to all OS users. Consider choosing a different directory.
      2019-10-30T12:11:45.510808Z 0 [Warning] [MY-000067] [Server] unknown variable 'loose-mysqlx-ssl=DISABLED'.
      2019-10-30T12:11:45.510817Z 0 [Warning] [MY-000067] [Server] unknown variable 'loose-debug-sync-timeout=600'.
      2019-10-30T12:11:45.510825Z 0 [Warning] [MY-000067] [Server] unknown variable 'loose-innodb-debug-force-scrubbing=ON'.
      2019-10-30T12:11:45.510833Z 0 [ERROR] [MY-000067] [Server] unknown variable 'innodb-encrypt-tables=KEYRING_ON'.
      2019-10-30T12:11:45.511235Z 0 [ERROR] [MY-010119] [Server] Aborting
      2019-10-30T12:11:45.600765Z 0 [Note] [MY-012330] [InnoDB] FTS optimize thread exiting.
      2019-10-30T12:11:46.502395Z 0 [Note] [MY-010120] [Server] Binlog end
      2019-10-30T12:11:46.505882Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'mysqlx_cache_cleaner'
      2019-10-30T12:11:46.505896Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'mysqlx'
      2019-10-30T12:11:46.506327Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'ngram'
      2019-10-30T12:11:46.506332Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'BLACKHOLE'
      2019-10-30T12:11:46.506340Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'ARCHIVE'
      2019-10-30T12:11:46.506345Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'TempTable'
      2019-10-30T12:11:46.506350Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'MRG_MYISAM'
      2019-10-30T12:11:46.506355Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'MyISAM'
      2019-10-30T12:11:46.506368Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_CHANGED_PAGES'
      2019-10-30T12:11:46.506373Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_TABLESPACES_SCRUBBING'
      2019-10-30T12:11:46.506382Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_TABLESPACES_ENCRYPTION'
      2019-10-30T12:11:46.506387Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_SESSION_TEMP_TABLESPACES'
      2019-10-30T12:11:46.506391Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_CACHED_INDEXES'
      2019-10-30T12:11:46.506397Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_VIRTUAL'
      2019-10-30T12:11:46.506401Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_COLUMNS'
      2019-10-30T12:11:46.506406Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_TABLESPACES'
      2019-10-30T12:11:46.506410Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_INDEXES'
      2019-10-30T12:11:46.506415Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_TABLESTATS'
      2019-10-30T12:11:46.506419Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_TABLES'
      2019-10-30T12:11:46.506423Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
      2019-10-30T12:11:46.506427Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
      2019-10-30T12:11:46.506431Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_FT_CONFIG'
      2019-10-30T12:11:46.506435Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_FT_BEING_DELETED'
      2019-10-30T12:11:46.506440Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_FT_DELETED'
      2019-10-30T12:11:46.506445Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
      2019-10-30T12:11:46.506449Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_METRICS'
      2019-10-30T12:11:46.506454Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_TEMP_TABLE_INFO'
      2019-10-30T12:11:46.506459Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
      2019-10-30T12:11:46.506464Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
      2019-10-30T12:11:46.506468Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_BUFFER_PAGE'
      2019-10-30T12:11:46.506472Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
      2019-10-30T12:11:46.506477Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_CMP_PER_INDEX'
      2019-10-30T12:11:46.506482Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_CMPMEM_RESET'
      2019-10-30T12:11:46.506487Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_CMPMEM'
      2019-10-30T12:11:46.506491Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_CMP_RESET'
      2019-10-30T12:11:46.506496Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_CMP'
      2019-10-30T12:11:46.506501Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_TRX'
      2019-10-30T12:11:46.506505Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'InnoDB'
      2019-10-30T12:11:46.506530Z 0 [Note] [MY-013072] [InnoDB] Starting shutdown...
      2019-10-30T12:11:46.606737Z 0 [Note] [MY-011944] [InnoDB] Dumping buffer pool(s) to /var/tmp/portage/dev-db/percona-server-8.0.16.7/work/percona-server-8.0.16.7_build/mysql-test/var/mysqld.1/data/ib_buffer_pool
      2019-10-30T12:11:46.609499Z 0 [Note] [MY-011944] [InnoDB] Buffer pool(s) dump completed at 191030 15:11:46
      2019-10-30T12:11:46.706753Z 0 [Note] [MY-013076] [InnoDB] Waiting for page_cleaner to finish flushing of buffer pool.
      2019-10-30T12:11:47.207223Z 0 [Note] [MY-013084] [InnoDB] Log background threads are being closed...
      2019-10-30T12:11:47.719720Z 0 [Note] [MY-012980] [InnoDB] Shutdown completed; log sequence number 19636379
      2019-10-30T12:11:47.720539Z 0 [Note] [MY-012255] [InnoDB] Removed temporary tablespace data file: "ibtmp1"
      2019-10-30T12:11:47.720549Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'MEMORY'
      2019-10-30T12:11:47.720554Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'CSV'
      2019-10-30T12:11:47.720561Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'PERFORMANCE_SCHEMA'
      2019-10-30T12:11:47.720619Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'sha2_cache_cleaner'
      2019-10-30T12:11:47.720624Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'caching_sha2_password'
      2019-10-30T12:11:47.720630Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'sha256_password'
      2019-10-30T12:11:47.720634Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'mysql_native_password'
      2019-10-30T12:11:47.720784Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'binlog'
      2019-10-30T12:11:47.735298Z 0 [System] [MY-010910] [Server] /var/tmp/portage/dev-db/percona-server-8.0.16.7/work/percona-server-8.0.16.7_build/runtime_output_directory/mysqld: Shutdown complete (mysqld 8.0.16-7)  Gentoo Linux percona-server-8.0.16.7.
      safe_process[105246]: Child process: 105247, exit: 1
      
      
      
      Failed to start mysqld.1
      
       - the logfile can be found in '/var/tmp/portage/dev-db/percona-server-8.0.16.7/work/percona-server-8.0.16.7_build/mysql-test/var/log/innodb.innodb_scrub_background/innodb_scrub_background.log'
      
      ------------------------------------------------------------------------------
      The servers were restarted 0 times
      The servers were reinitialized 0 times
      Spent 0.000 of 11 seconds executing testcases
      
      Completed: Failed 1/1 tests, 0.00% were successful.
      
      Failing test(s): innodb.innodb_scrub_background
      

        Smart Checklist

          Attachments

            Activity

              People

              • Assignee:
                Unassigned
                Reporter:
                Whissi Thomas Deutschmann
              • Votes:
                0 Vote for this issue
                Watchers:
                2 Start watching this issue

                Dates

                • Created:
                  Updated:
                  Resolved: