[MDEV-11671] Duplicated [NOTE] output for changed innodb_page_size - Jira

Details

Type: Bug
Status: Closed (View Workflow)
Priority: Minor
Resolution: Fixed
Affects Version/s: 10.1.20
Fix Version/s: 10.1.22, 10.2.4
Component/s: Storage Engine - InnoDB
Labels:
None

Description

running mysql_install_db:

Installing MariaDB/MySQL system tables in '/mnt/workspace/PTB-run-mariadb-10.1.20-pxb-2.4.5/BTYPE/release/Host/debian-jessie-32bit/mnt/var/ps_5.7.15_sysbench/test-0/mysql.1/data' ...

2016-12-27  7:58:55 3074996032 [Note] /mnt/workspace/PTB-run-mariadb-10.1.20-pxb-2.4.5/BTYPE/release/Host/debian-jessie-32bit/mariadb-10.1.20-linux-i686/bin/mysqld (mysqld 10.1.20-MariaDB) starting as process 1458 ...

2016-12-27  7:58:55 3074996032 [Note] InnoDB: innodb_page_size has been changed from default value 16384 to 65536d.

2016-12-27 07:58:55 b748b740 InnoDB: innodb-page-size has been changed from the default value 16384 to 65536.

2016-12-27  7:58:55 3074996032 [Note] InnoDB: Using mutexes to ref count buffer pool pages

2016-12-27  7:58:55 3074996032 [Note] InnoDB: The InnoDB memory heap is disabled

2016-12-27  7:58:55 3074996032 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins

2016-12-27  7:58:55 3074996032 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier

2016-12-27  7:58:55 3074996032 [Note] InnoDB: Compressed tables use zlib 1.2.3

2016-12-27  7:58:55 3074996032 [Note] InnoDB: Using Linux native AIO

2016-12-27  7:58:55 3074996032 [Note] InnoDB: Using generic crc32 instructions

2016-12-27  7:58:55 3074996032 [Note] InnoDB: Initializing buffer pool, size = 1.0G

2016-12-27  7:58:56 3074996032 [Note] InnoDB: Completed initialization of buffer pool

2016-12-27  7:58:56 3074996032 [Note] InnoDB: The first specified data file ./ibdata1 did not exist: a new database to be created!

2016-12-27  7:58:56 3074996032 [Note] InnoDB: Setting file ./ibdata1 size to 12 MB

2016-12-27  7:58:56 3074996032 [Note] InnoDB: Database physically writes the file full: wait...

2016-12-27  7:58:56 3074996032 [Note] InnoDB: Setting log file ./ib_logfile101 size to 1024 MB

InnoDB: Progress in MB: 100 200 300 400 500 600 700 800 900 1000

2016-12-27  7:58:58 3074996032 [Note] InnoDB: Setting log file ./ib_logfile1 size to 1024 MB

InnoDB: Progress in MB: 100 200 300 400 500 600 700 800 900 1000

2016-12-27  7:59:01 3074996032 [Note] InnoDB: Renaming log file ./ib_logfile101 to ./ib_logfile0

2016-12-27  7:59:01 3074996032 [Warning] InnoDB: New log files created, LSN=130980

2016-12-27  7:59:01 3074996032 [Note] InnoDB: Doublewrite buffer not found: creating new

2016-12-27  7:59:01 3074996032 [Note] InnoDB: Doublewrite buffer created

2016-12-27  7:59:01 3074996032 [Note] InnoDB: 128 rollback segment(s) are active.

2016-12-27  7:59:01 3074996032 [Warning] InnoDB: Creating foreign key constraint system tables.

2016-12-27  7:59:01 3074996032 [Note] InnoDB: Foreign key constraint system tables created

2016-12-27  7:59:01 3074996032 [Note] InnoDB: Creating tablespace and datafile system tables.

2016-12-27  7:59:01 3074996032 [Note] InnoDB: Tablespace and datafile system tables created.

2016-12-27  7:59:01 3074996032 [Note] InnoDB: Creating zip_dict and zip_dict_cols system tables.

2016-12-27  7:59:01 3074996032 [Note] InnoDB: zip_dict and zip_dict_cols system tables created.

2016-12-27  7:59:01 3074996032 [Note] InnoDB: Waiting for purge to start

2016-12-27  7:59:01 3074996032 [Note] InnoDB:  Percona XtraDB (http://www.percona.com) 5.6.34-79.1 started; log sequence number 0

2016-12-27  7:59:01 1182792512 [Note] InnoDB: Dumping buffer pool(s) not yet started

Starting:

2016-12-27  7:59:44 3074840384 [Note] mysqld (mysqld 10.1.20-MariaDB) starting as process 1935 ...

2016-12-27  7:59:44 3074840384 [Note] InnoDB: innodb_page_size has been changed from default value 16384 to 65536d.

2016-12-27 07:59:44 b7465740 InnoDB: innodb-page-size has been changed from the default value 16384 to 65536.

2016-12-27  7:59:44 3074840384 [Note] InnoDB: Using mutexes to ref count buffer pool pages

2016-12-27  7:59:44 3074840384 [Note] InnoDB: The InnoDB memory heap is disabled

2016-12-27  7:59:44 3074840384 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins

2016-12-27  7:59:44 3074840384 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier

2016-12-27  7:59:44 3074840384 [Note] InnoDB: Compressed tables use zlib 1.2.3

2016-12-27  7:59:44 3074840384 [Note] InnoDB: Using Linux native AIO

2016-12-27  7:59:44 3074840384 [Note] InnoDB: Using generic crc32 instructions

2016-12-27  7:59:44 3074840384 [Note] InnoDB: Initializing buffer pool, size = 1.0G

2016-12-27  7:59:44 3074840384 [Note] InnoDB: Completed initialization of buffer pool

2016-12-27  7:59:44 3074840384 [Note] InnoDB: Highest supported file format is Barracuda.

2016-12-27  7:59:45 3074840384 [Note] InnoDB: 128 rollback segment(s) are active.

2016-12-27  7:59:45 3074840384 [Note] InnoDB: Waiting for purge to start

2016-12-27  7:59:45 3074840384 [Note] InnoDB:  Percona XtraDB (http://www.percona.com) 5.6.34-79.1 started; log sequence number 431859117

2016-12-27  7:59:45 1186986816 [Note] InnoDB: Dumping buffer pool(s) not yet started

2016-12-27  7:59:45 3074840384 [Note] Plugin 'FEEDBACK' is disabled.

2016-12-27  7:59:46 3074840384 [Note] Server socket created on IP: '::'.

2016-12-27  7:59:46 3074840384 [Note] mysqld: ready for connections.

Version: '10.1.20-MariaDB'  socket: '/tmp/mysql.sock'  port: 10000  MariaDB Server

2016-12-27  8:00:53 1638997824 [Note] mysqld: Normal shutdown

2016-12-27  8:00:53 1638997824 [Note] Event Scheduler: Purging the queue. 0 events

2016-12-27  8:00:53 1145043776 [Note] InnoDB: FTS optimize thread exiting.

2016-12-27  8:00:53 1638997824 [Note] InnoDB: Starting shutdown...

2016-12-27  8:00:53 1638997824 [Note] InnoDB: Waiting for page_cleaner to finish flushing of buffer pool

2016-12-27  8:00:59 1638997824 [Note] InnoDB: Shutdown completed; log sequence number 437747017

2016-12-27  8:00:59 1638997824 [Note] mysqld: Shutdown complete

Note the:

2016-12-27  7:59:44 3074840384 [Note] InnoDB: innodb_page_size has been changed from default value 16384 to 65536d.

2016-12-27 07:59:44 b7465740 InnoDB: innodb-page-size has been changed from the default value 16384 to 65536.

Attachments

Activity

Ascending order - Click to sort in descending order

Elena Stepanova added a comment - 2016-12-27 15:04

jplindst, could you please clean up the logging while you're working on innodb_page_size-related issues anyway?

Elena Stepanova added a comment - 2016-12-27 15:04 jplindst , could you please clean up the logging while you're working on innodb_page_size -related issues anyway?

Marko Mäkelä added a comment - 2017-01-18 06:55

For 10.2, I would suggest the following patch:

diff --git a/storage/innobase/handler/ha_innodb.cc b/storage/innobase/handler/ha_innodb.cc

index 93f5c936915..23eb399b948 100644

--- a/storage/innobase/handler/ha_innodb.cc

+++ b/storage/innobase/handler/ha_innodb.cc

@@ -3973,21 +3973,15 @@ innobase_init(

 	any functions that could possibly allocate memory. */

 	ut_new_boot();

-	if (UNIV_PAGE_SIZE != UNIV_PAGE_SIZE_DEF) {

-		ib::info() << "innodb_page_size has been "

-			<< "changed from default value "

-			<< UNIV_PAGE_SIZE_DEF << " to " << UNIV_PAGE_SIZE;

-		/* There is hang on buffer pool when trying to get a new

-		page if buffer pool size is too small for large page sizes */

-		if (UNIV_PAGE_SIZE > UNIV_PAGE_SIZE_DEF

-		    && innobase_buffer_pool_size < (24 * 1024 * 1024)) {

-			ib::info() << "innodb_page_size="

-				<< UNIV_PAGE_SIZE << " requires "

-				<< "innodb_buffer_pool_size > 24M current "

-				<< innobase_buffer_pool_size;

-			goto error;

-		}

+	/* The buffer pool needs to be able to accommodate enough many

+	pages, even for larger pages */

+	if (UNIV_PAGE_SIZE > UNIV_PAGE_SIZE_DEF

+	    && innobase_buffer_pool_size < (24 * 1024 * 1024)) {

+		ib::info() << "innodb_page_size="

+			<< UNIV_PAGE_SIZE << " requires "

+			<< "innodb_buffer_pool_size > 24M current "

+			<< innobase_buffer_pool_size;

+		goto error;

 #ifndef HAVE_LZ4

@@ -4313,9 +4307,7 @@ innobase_init(

 	srv_log_file_size = (ib_uint64_t) innobase_log_file_size;

 	if (UNIV_PAGE_SIZE_DEF != srv_page_size) {

-		ib::warn() << "innodb-page-size has been changed from the"

-			" default value " << UNIV_PAGE_SIZE_DEF << " to "

-			<< srv_page_size << ".";

+		ib::info() << "innodb_page_size=" << srv_page_size;

 	if (srv_log_write_ahead_size > srv_page_size) {

Marko Mäkelä added a comment - 2017-01-18 06:55 For 10.2, I would suggest the following patch: diff --git a/storage/innobase/handler/ha_innodb.cc b/storage/innobase/handler/ha_innodb.cc index 93f5c936915..23eb399b948 100644 --- a/storage/innobase/handler/ha_innodb.cc +++ b/storage/innobase/handler/ha_innodb.cc @@ -3973,21 +3973,15 @@ innobase_init( any functions that could possibly allocate memory. */ ut_new_boot(); - if (UNIV_PAGE_SIZE != UNIV_PAGE_SIZE_DEF) { - ib::info() << "innodb_page_size has been " - << "changed from default value " - << UNIV_PAGE_SIZE_DEF << " to " << UNIV_PAGE_SIZE; - - /* There is hang on buffer pool when trying to get a new - page if buffer pool size is too small for large page sizes */ - if (UNIV_PAGE_SIZE > UNIV_PAGE_SIZE_DEF - && innobase_buffer_pool_size < (24 * 1024 * 1024)) { - ib::info() << "innodb_page_size=" - << UNIV_PAGE_SIZE << " requires " - << "innodb_buffer_pool_size > 24M current " - << innobase_buffer_pool_size; - goto error; - } + /* The buffer pool needs to be able to accommodate enough many + pages, even for larger pages */ + if (UNIV_PAGE_SIZE > UNIV_PAGE_SIZE_DEF + && innobase_buffer_pool_size < (24 * 1024 * 1024)) { + ib::info() << "innodb_page_size=" + << UNIV_PAGE_SIZE << " requires " + << "innodb_buffer_pool_size > 24M current " + << innobase_buffer_pool_size; + goto error; } #ifndef HAVE_LZ4 @@ -4313,9 +4307,7 @@ innobase_init( srv_log_file_size = (ib_uint64_t) innobase_log_file_size; if (UNIV_PAGE_SIZE_DEF != srv_page_size) { - ib::warn() << "innodb-page-size has been changed from the" - " default value " << UNIV_PAGE_SIZE_DEF << " to " - << srv_page_size << "."; + ib::info() << "innodb_page_size=" << srv_page_size; } if (srv_log_write_ahead_size > srv_page_size) {

Marko Mäkelä added a comment - 2017-01-31 12:25

http://lists.askmonty.org/pipermail/commits/2017-January/010588.html

Marko Mäkelä added a comment - 2017-01-31 12:25 http://lists.askmonty.org/pipermail/commits/2017-January/010588.html

Jan Lindström (Inactive) added a comment - 2017-01-31 12:32

ok to push. Here for 10.1:

http://lists.askmonty.org/pipermail/commits/2017-January/010590.html

Jan Lindström (Inactive) added a comment - 2017-01-31 12:32 ok to push. Here for 10.1: http://lists.askmonty.org/pipermail/commits/2017-January/010590.html

Jan Lindström (Inactive) added a comment - 2017-01-31 16:34

10.1:

commit 17cc6198479d83bd4f88e9fff197b0b342dca5d9
Author: Jan Lindström <jan.lindstrom@mariadb.com>
Date: Tue Jan 31 15:42:52 2017 +0200

~~MDEV-11671~~: Duplicated [NOTE] output for changed innodb_page_size

Remove duplicated output and change output level to info.

Jan Lindström (Inactive) added a comment - 2017-01-31 16:34 10.1: commit 17cc6198479d83bd4f88e9fff197b0b342dca5d9 Author: Jan Lindström <jan.lindstrom@mariadb.com> Date: Tue Jan 31 15:42:52 2017 +0200 MDEV-11671 : Duplicated [NOTE] output for changed innodb_page_size Remove duplicated output and change output level to info.

People

Assignee:: Marko Mäkelä

Reporter:: Shahriyar Rzayev

Votes:: 1 Vote for this issue

Watchers:: 5 Start watching this issue

Dates

Created:: 2016-12-27 13:55

Updated:: 2017-02-01 08:12

Resolved:: 2017-02-01 08:12

Git Integration

Error rendering 'com.xiplink.jira.git.jira_git_plugin:git-issue-webpanel'. Please contact your Jira administrators.