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

innodb.percona_log_encrypt_failure test fails: Result length mismatch: Pattern "keyring plugin fail" not found

    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
      ------------------------------------------------------------------------------
      [100%] innodb.percona_log_encrypt_failure        [ fail ]
              Test ended at 2019-10-30 12:43:19
      
      CURRENT_TEST: innodb.percona_log_encrypt_failure
      --- /var/tmp/portage/dev-db/percona-server-8.0.16.7/work/mysql/mysql-test/suite/innodb/r/percona_log_encrypt_failure.result     2019-08-14 17:25:47.000000000 +0300
      +++ /var/tmp/portage/dev-db/percona-server-8.0.16.7/work/percona-server-8.0.16.7_build/mysql-test/var/log/percona_log_encrypt_failure.reject    2019-10-30 15:43:19.008937621 +0300
      @@ -1,2 +1,2 @@
      -Pattern "keyring plugin fail" found
      +Pattern "keyring plugin fail" not found
       # restart
      
      mysqltest: Result length mismatch
      
      safe_process[106169]: Child process: 106170, exit: 1
      
      Server [mysqld.1 - pid: 106178, winpid: 106178] log:
      Server log from this test:
      ----------SERVER LOG START-----------
      2019-10-30T12:43:13.234677Z 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:43:13.234688Z 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:43:13.234714Z 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:43:13.234724Z 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 106123
      2019-10-30T12:43:13.255946Z 0 [Note] [MY-012366] [InnoDB] Using Linux native AIO
      2019-10-30T12:43:13.256089Z 0 [Note] [MY-010747] [Server] Plugin 'FEDERATED' is disabled.
      2019-10-30T12:43:13.257346Z 1 [Note] [MY-012932] [InnoDB] PUNCH HOLE support available
      2019-10-30T12:43:13.257382Z 1 [Note] [MY-012943] [InnoDB] Mutexes and rw_locks use GCC atomic builtins
      2019-10-30T12:43:13.257404Z 1 [Note] [MY-012944] [InnoDB] Uses event mutexes
      2019-10-30T12:43:13.257422Z 1 [Note] [MY-012945] [InnoDB] GCC builtin __atomic_thread_fence() is used for memory barrier
      2019-10-30T12:43:13.257443Z 1 [Note] [MY-012948] [InnoDB] Compressed tables use zlib 1.2.11
      2019-10-30T12:43:13.257782Z 1 [Note] [MY-013251] [InnoDB] Number of pools: 1
      2019-10-30T12:43:13.257926Z 1 [Note] [MY-012951] [InnoDB] Using CPU crc32 instructions
      2019-10-30T12:43:13.258443Z 1 [Note] [MY-012203] [InnoDB] Directories to scan './;./;./'
      2019-10-30T12:43:13.258513Z 1 [Note] [MY-012204] [InnoDB] Scanning './'
      2019-10-30T12:43:13.259493Z 1 [Note] [MY-012208] [InnoDB] Completed space ID check of 6 files.
      2019-10-30T12:43:13.260672Z 1 [Note] [MY-012955] [InnoDB] Initializing buffer pool, total size = 24.000000M, instances = 1, chunk size =24.000000M
      2019-10-30T12:43:13.262139Z 1 [Note] [MY-012957] [InnoDB] Completed initialization of buffer pool
      2019-10-30T12:43:13.262637Z 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:43:13.274059Z 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:43:13.340436Z 1 [Note] [MY-013086] [InnoDB] Starting to parse redo log at lsn = 19343885, whereas checkpoint_lsn = 19343934
      2019-10-30T12:43:13.348603Z 1 [Note] [MY-013083] [InnoDB] Log background threads are being started...
      2019-10-30T12:43:13.360919Z 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:43:13.360956Z 1 [Note] [MY-012532] [InnoDB] Applying a batch of 0 redo log records ...
      2019-10-30T12:43:13.360971Z 1 [Note] [MY-012535] [InnoDB] Apply batch completed!
      2019-10-30T12:43:13.361074Z 1 [Note] [MY-013252] [InnoDB] Using undo tablespace './undo_001'.
      2019-10-30T12:43:13.361213Z 1 [Note] [MY-013252] [InnoDB] Using undo tablespace './undo_002'.
      2019-10-30T12:43:13.363163Z 1 [Note] [MY-012910] [InnoDB] Opened 2 existing undo tablespaces.
      2019-10-30T12:43:13.371167Z 1 [Note] [MY-012923] [InnoDB] Creating shared tablespace for temporary tables
      2019-10-30T12:43:13.371246Z 1 [Note] [MY-012265] [InnoDB] Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
      2019-10-30T12:43:13.384851Z 1 [Note] [MY-012266] [InnoDB] File './ibtmp1' size is now 12 MB.
      2019-10-30T12:43:13.385036Z 1 [Note] [MY-011825] [InnoDB] Scanning temp tablespace dir:'./#innodb_temp/'
      2019-10-30T12:43:13.478467Z 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:43:13.478619Z 1 [Note] [MY-012976] [InnoDB] Percona XtraDB (http://www.percona.com) 8.0.16-7 started; log sequence number 19343934
      2019-10-30T12:43:13.482658Z 1 [Note] [MY-011089] [Server] Data dictionary restarting version '80016'.
      2019-10-30T12:43:13.535471Z 1 [Note] [MY-012357] [InnoDB] Reading DD tablespace files
      2019-10-30T12:43:13.536086Z 1 [Note] [MY-012356] [InnoDB] Validated 8/8  tablespaces
      2019-10-30T12:43:13.536433Z 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:43:13.539581Z 0 [Note] [MY-011946] [InnoDB] Buffer pool(s) load completed at 191030 15:43:13
      2019-10-30T12:43:13.551809Z 1 [Note] [MY-010006] [Server] Using data dictionary with version '80016'.
      2019-10-30T12:43:13.613311Z 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:43:13.632190Z 0 [Note] [MY-012487] [InnoDB] DDL log recovery : begin
      2019-10-30T12:43:13.632238Z 0 [Note] [MY-012488] [InnoDB] DDL log recovery : end
      2019-10-30T12:43:13.632355Z 0 [Note] [MY-012922] [InnoDB] Waiting for purge to start
      2019-10-30T12:43:13.682550Z 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: d66aebb9-fb12-11e9-a0f1-7085c2a9c0a3.
      2019-10-30T12:43:13.705612Z 0 [Note] [MY-010303] [Server] Skipping generation of SSL certificates as options related to SSL are specified.
      2019-10-30T12:43:13.706246Z 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:43:13.706262Z 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:43:13.706270Z 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:43:13.706287Z 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:43:13.706298Z 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:43:13.706360Z 0 [Note] [MY-010252] [Server] Server hostname (bind-address): '*'; port: 13000
      2019-10-30T12:43:13.706387Z 0 [Note] [MY-010253] [Server] IPv6 is available.
      2019-10-30T12:43:13.706397Z 0 [Note] [MY-010264] [Server]   - '::' resolves to '::';
      2019-10-30T12:43:13.706430Z 0 [Note] [MY-010251] [Server] Server socket created on IP: '::'.
      2019-10-30T12:43:13.709675Z 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:43:13.711596Z 0 [Warning] [MY-000067] [Server] unknown variable 'loose-mysqlx-ssl=DISABLED'.
      2019-10-30T12:43:13.711605Z 0 [Warning] [MY-000067] [Server] unknown variable 'loose-debug-sync-timeout=600'.
      2019-10-30T12:43:13.720684Z 0 [Note] [MY-011025] [Repl] Failed to start slave threads for channel ''.
      2019-10-30T12:43:13.721735Z 4 [Note] [MY-010051] [Server] Event Scheduler: scheduler thread started with id 4
      2019-10-30T12:43:13.722247Z 0 [System] [MY-010931] [Server] /var/tmp/portage/dev-db/percona-server-8.0.16.7/work/percona-server-8.0.16.7_build/runtime_output_directory/mysqld: ready for connections. Version: '8.0.16-7'  socket: '/tmp/I3ballAAD8/mysqld.1.sock'  port: 13000  Gentoo Linux percona-server-8.0.16.7.
      2019-10-30T12:43:13.805916Z 0 [Note] [MY-011240] [Server] Plugin mysqlx reported: 'Using SSL configuration from MySQL Server'
      2019-10-30T12:43:13.806583Z 0 [Note] [MY-011243] [Server] Plugin mysqlx reported: 'Using OpenSSL for TLS connections'
      2019-10-30T12:43:13.806642Z 0 [Note] [MY-011332] [Server] Plugin mysqlx reported: 'IPv6 is available'
      2019-10-30T12:43:13.810306Z 0 [Note] [MY-011323] [Server] Plugin mysqlx reported: 'X Plugin ready for connections. bind-address: '::' port: 13010'
      2019-10-30T12:43:13.810322Z 0 [Note] [MY-011323] [Server] Plugin mysqlx reported: 'X Plugin ready for connections. socket: '/tmp/I3ballAAD8/mysqlx.1.sock''
      2019-10-30T12:43:13.810333Z 0 [System] [MY-011323] [Server] X Plugin ready for connections. Socket: '/tmp/I3ballAAD8/mysqlx.1.sock' bind-address: '::' port: 13010
      2019-10-30T12:43:13.825351Z 9 [System] [MY-013172] [Server] Received SHUTDOWN from user root. Shutting down mysqld (Version: 8.0.16-7).
      2019-10-30T12:43:14.808431Z 0 [Note] [MY-010067] [Server] Giving 1 client threads a chance to die gracefully
      2019-10-30T12:43:14.808465Z 0 [Note] [MY-010117] [Server] Shutting down slave threads
      2019-10-30T12:43:14.808477Z 0 [Note] [MY-010054] [Server] Event Scheduler: Killing the scheduler thread, thread id 4
      2019-10-30T12:43:14.808489Z 0 [Note] [MY-010050] [Server] Event Scheduler: Waiting for the scheduler thread to reply
      2019-10-30T12:43:14.808613Z 0 [Note] [MY-010048] [Server] Event Scheduler: Stopped
      2019-10-30T12:43:14.808624Z 0 [Note] [MY-010118] [Server] Forcefully disconnecting 0 remaining clients
      2019-10-30T12:43:14.808636Z 0 [Note] [MY-010043] [Server] Event Scheduler: Purging the queue. 0 events
      2019-10-30T12:43:14.808821Z 0 [Note] [MY-012330] [InnoDB] FTS optimize thread exiting.
      2019-10-30T12:43:14.911795Z 0 [Note] [MY-010120] [Server] Binlog end
      2019-10-30T12:43:14.916598Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'mysqlx_cache_cleaner'
      2019-10-30T12:43:14.916646Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'mysqlx'
      2019-10-30T12:43:14.917008Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'ngram'
      2019-10-30T12:43:14.917022Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'BLACKHOLE'
      2019-10-30T12:43:14.917031Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'ARCHIVE'
      2019-10-30T12:43:14.917038Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'TempTable'
      2019-10-30T12:43:14.917044Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'MRG_MYISAM'
      2019-10-30T12:43:14.917050Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'MyISAM'
      2019-10-30T12:43:14.917065Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_CHANGED_PAGES'
      2019-10-30T12:43:14.917072Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_TABLESPACES_SCRUBBING'
      2019-10-30T12:43:14.917079Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_TABLESPACES_ENCRYPTION'
      2019-10-30T12:43:14.917084Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_SESSION_TEMP_TABLESPACES'
      2019-10-30T12:43:14.917090Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_CACHED_INDEXES'
      2019-10-30T12:43:14.917095Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_VIRTUAL'
      2019-10-30T12:43:14.917101Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_COLUMNS'
      2019-10-30T12:43:14.917106Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_TABLESPACES'
      2019-10-30T12:43:14.917112Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_INDEXES'
      2019-10-30T12:43:14.917117Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_TABLESTATS'
      2019-10-30T12:43:14.917122Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_TABLES'
      2019-10-30T12:43:14.917127Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
      2019-10-30T12:43:14.917133Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
      2019-10-30T12:43:14.917138Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_FT_CONFIG'
      2019-10-30T12:43:14.917144Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_FT_BEING_DELETED'
      2019-10-30T12:43:14.917149Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_FT_DELETED'
      2019-10-30T12:43:14.917171Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
      2019-10-30T12:43:14.917176Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_METRICS'
      2019-10-30T12:43:14.917182Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_TEMP_TABLE_INFO'
      2019-10-30T12:43:14.917187Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
      2019-10-30T12:43:14.917192Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
      2019-10-30T12:43:14.917198Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_BUFFER_PAGE'
      2019-10-30T12:43:14.917203Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
      2019-10-30T12:43:14.917208Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_CMP_PER_INDEX'
      2019-10-30T12:43:14.917214Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_CMPMEM_RESET'
      2019-10-30T12:43:14.917219Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_CMPMEM'
      2019-10-30T12:43:14.917224Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_CMP_RESET'
      2019-10-30T12:43:14.917230Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_CMP'
      2019-10-30T12:43:14.917235Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'INNODB_TRX'
      2019-10-30T12:43:14.917241Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'InnoDB'
      2019-10-30T12:43:14.920968Z 0 [Note] [MY-013072] [InnoDB] Starting shutdown...
      2019-10-30T12:43:15.021381Z 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:43:15.027356Z 0 [Note] [MY-011944] [InnoDB] Buffer pool(s) dump completed at 191030 15:43:15
      2019-10-30T12:43:15.621607Z 0 [Note] [MY-013076] [InnoDB] Waiting for page_cleaner to finish flushing of buffer pool.
      2019-10-30T12:43:16.322206Z 0 [Note] [MY-013084] [InnoDB] Log background threads are being closed...
      2019-10-30T12:43:16.954572Z 0 [Note] [MY-012980] [InnoDB] Shutdown completed; log sequence number 19706504
      2019-10-30T12:43:16.955375Z 0 [Note] [MY-012255] [InnoDB] Removed temporary tablespace data file: "ibtmp1"
      2019-10-30T12:43:16.955388Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'MEMORY'
      2019-10-30T12:43:16.955394Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'CSV'
      2019-10-30T12:43:16.955400Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'PERFORMANCE_SCHEMA'
      2019-10-30T12:43:16.955435Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'sha2_cache_cleaner'
      2019-10-30T12:43:16.955440Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'caching_sha2_password'
      2019-10-30T12:43:16.955445Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'sha256_password'
      2019-10-30T12:43:16.955448Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'mysql_native_password'
      2019-10-30T12:43:16.955596Z 0 [Note] [MY-010733] [Server] Shutting down plugin 'binlog'
      2019-10-30T12:43:16.972831Z 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.
      2019-10-30T12:43:18.417321Z 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:43:18.417332Z 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:43:18.417359Z 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:43:18.417371Z 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 106179
      2019-10-30T12:43:18.426668Z 0 [Note] [MY-012366] [InnoDB] Using Linux native AIO
      2019-10-30T12:43:18.426807Z 0 [Note] [MY-010747] [Server] Plugin 'FEDERATED' is disabled.
      2019-10-30T12:43:18.428000Z 1 [Note] [MY-012932] [InnoDB] PUNCH HOLE support available
      2019-10-30T12:43:18.428026Z 1 [Note] [MY-012943] [InnoDB] Mutexes and rw_locks use GCC atomic builtins
      2019-10-30T12:43:18.428040Z 1 [Note] [MY-012944] [InnoDB] Uses event mutexes
      2019-10-30T12:43:18.428052Z 1 [Note] [MY-012945] [InnoDB] GCC builtin __atomic_thread_fence() is used for memory barrier
      2019-10-30T12:43:18.428065Z 1 [Note] [MY-012948] [InnoDB] Compressed tables use zlib 1.2.11
      2019-10-30T12:43:18.428288Z 1 [Note] [MY-013251] [InnoDB] Number of pools: 1
      2019-10-30T12:43:18.428382Z 1 [Note] [MY-012951] [InnoDB] Using CPU crc32 instructions
      2019-10-30T12:43:18.428752Z 1 [Note] [MY-012203] [InnoDB] Directories to scan './;./;./'
      2019-10-30T12:43:18.428798Z 1 [Note] [MY-012204] [InnoDB] Scanning './'
      2019-10-30T12:43:18.429430Z 1 [Note] [MY-012208] [InnoDB] Completed space ID check of 6 files.
      2019-10-30T12:43:18.430252Z 1 [Note] [MY-012955] [InnoDB] Initializing buffer pool, total size = 24.000000M, instances = 1, chunk size =24.000000M
      2019-10-30T12:43:18.431514Z 1 [Note] [MY-012957] [InnoDB] Completed initialization of buffer pool
      2019-10-30T12:43:18.432124Z 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:43:18.443356Z 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:43:18.509017Z 1 [Note] [MY-013086] [InnoDB] Starting to parse redo log at lsn = 19706402, whereas checkpoint_lsn = 19706504
      2019-10-30T12:43:18.515697Z 1 [Note] [MY-013083] [InnoDB] Log background threads are being started...
      2019-10-30T12:43:18.526147Z 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:43:18.526185Z 1 [Note] [MY-012532] [InnoDB] Applying a batch of 0 redo log records ...
      2019-10-30T12:43:18.526197Z 1 [Note] [MY-012535] [InnoDB] Apply batch completed!
      2019-10-30T12:43:18.526301Z 1 [Note] [MY-013252] [InnoDB] Using undo tablespace './undo_001'.
      2019-10-30T12:43:18.526437Z 1 [Note] [MY-013252] [InnoDB] Using undo tablespace './undo_002'.
      2019-10-30T12:43:18.528278Z 1 [Note] [MY-012910] [InnoDB] Opened 2 existing undo tablespaces.
      2019-10-30T12:43:18.536114Z 1 [Note] [MY-012923] [InnoDB] Creating shared tablespace for temporary tables
      2019-10-30T12:43:18.536191Z 1 [Note] [MY-012265] [InnoDB] Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
      2019-10-30T12:43:18.548530Z 1 [Note] [MY-012266] [InnoDB] File './ibtmp1' size is now 12 MB.
      2019-10-30T12:43:18.548733Z 1 [Note] [MY-011825] [InnoDB] Scanning temp tablespace dir:'./#innodb_temp/'
      2019-10-30T12:43:18.643854Z 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:43:18.644002Z 1 [Note] [MY-012976] [InnoDB] Percona XtraDB (http://www.percona.com) 8.0.16-7 started; log sequence number 19706504
      2019-10-30T12:43:18.648221Z 1 [Note] [MY-011089] [Server] Data dictionary restarting version '80016'.
      2019-10-30T12:43:18.701214Z 1 [Note] [MY-012357] [InnoDB] Reading DD tablespace files
      2019-10-30T12:43:18.701828Z 1 [Note] [MY-012356] [InnoDB] Validated 8/8  tablespaces
      2019-10-30T12:43:18.702131Z 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:43:18.702622Z 0 [Note] [MY-011946] [InnoDB] Buffer pool(s) load completed at 191030 15:43:18
      2019-10-30T12:43:18.718596Z 1 [Note] [MY-010006] [Server] Using data dictionary with version '80016'.
      2019-10-30T12:43:18.735506Z 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:43:18.755769Z 0 [Note] [MY-012487] [InnoDB] DDL log recovery : begin
      2019-10-30T12:43:18.755825Z 0 [Note] [MY-012488] [InnoDB] DDL log recovery : end
      2019-10-30T12:43:18.755958Z 0 [Note] [MY-012922] [InnoDB] Waiting for purge to start
      2019-10-30T12:43:18.825853Z 0 [Note] [MY-010303] [Server] Skipping generation of SSL certificates as options related to SSL are specified.
      2019-10-30T12:43:18.826475Z 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:43:18.826492Z 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:43:18.826499Z 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:43:18.826514Z 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:43:18.826524Z 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:43:18.826601Z 0 [Note] [MY-010252] [Server] Server hostname (bind-address): '*'; port: 13000
      2019-10-30T12:43:18.826649Z 0 [Note] [MY-010253] [Server] IPv6 is available.
      2019-10-30T12:43:18.826670Z 0 [Note] [MY-010264] [Server]   - '::' resolves to '::';
      2019-10-30T12:43:18.826737Z 0 [Note] [MY-010251] [Server] Server socket created on IP: '::'.
      2019-10-30T12:43:18.830204Z 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:43:18.832387Z 0 [Warning] [MY-000067] [Server] unknown variable 'loose-mysqlx-ssl=DISABLED'.
      2019-10-30T12:43:18.832400Z 0 [Warning] [MY-000067] [Server] unknown variable 'loose-debug-sync-timeout=600'.
      2019-10-30T12:43:18.841609Z 0 [Note] [MY-011025] [Repl] Failed to start slave threads for channel ''.
      2019-10-30T12:43:18.842655Z 4 [Note] [MY-010051] [Server] Event Scheduler: scheduler thread started with id 4
      2019-10-30T12:43:18.843257Z 0 [System] [MY-010931] [Server] /var/tmp/portage/dev-db/percona-server-8.0.16.7/work/percona-server-8.0.16.7_build/runtime_output_directory/mysqld: ready for connections. Version: '8.0.16-7'  socket: '/tmp/I3ballAAD8/mysqld.1.sock'  port: 13000  Gentoo Linux percona-server-8.0.16.7.
      2019-10-30T12:43:18.972712Z 0 [Note] [MY-011240] [Server] Plugin mysqlx reported: 'Using SSL configuration from MySQL Server'
      2019-10-30T12:43:18.973474Z 0 [Note] [MY-011243] [Server] Plugin mysqlx reported: 'Using OpenSSL for TLS connections'
      2019-10-30T12:43:18.973527Z 0 [Note] [MY-011332] [Server] Plugin mysqlx reported: 'IPv6 is available'
      2019-10-30T12:43:18.977820Z 0 [Note] [MY-011323] [Server] Plugin mysqlx reported: 'X Plugin ready for connections. bind-address: '::' port: 13010'
      2019-10-30T12:43:18.977840Z 0 [Note] [MY-011323] [Server] Plugin mysqlx reported: 'X Plugin ready for connections. socket: '/tmp/I3ballAAD8/mysqlx.1.sock''
      2019-10-30T12:43:18.977856Z 0 [System] [MY-011323] [Server] X Plugin ready for connections. Socket: '/tmp/I3ballAAD8/mysqlx.1.sock' bind-address: '::' port: 13010
      ----------SERVER LOG END-------------
      
      
       - 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.percona_log_encrypt_failure/percona_log_encrypt_failure.log'
      
      ------------------------------------------------------------------------------
      The servers were restarted 0 times
      The servers were reinitialized 0 times
      Spent 0.000 of 14 seconds executing testcases
      
      Completed: Failed 1/1 tests, 0.00% were successful.
      
      Failing test(s): innodb.percona_log_encrypt_failure
      

        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: