[MDEV-11671] Duplicated [NOTE] output for changed innodb_page_size Created: 2016-12-27  Updated: 2017-02-01  Resolved: 2017-02-01

Status: Closed
Project: MariaDB Server
Component/s: Storage Engine - InnoDB
Affects Version/s: 10.1.20
Fix Version/s: 10.1.22, 10.2.4

Type: Bug Priority: Minor
Reporter: Shahriyar Rzayev Assignee: Marko Mäkelä
Resolution: Fixed Votes: 1
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.



 Comments   
Comment by Elena Stepanova [ 2016-12-27 ]

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

Comment by Marko Mäkelä [ 2017-01-18 ]

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

Comment by Marko Mäkelä [ 2017-01-31 ]

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

Comment by Jan Lindström (Inactive) [ 2017-01-31 ]

ok to push. Here for 10.1:

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

Comment by Jan Lindström (Inactive) [ 2017-01-31 ]

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.

Generated at Thu Feb 08 07:51:46 UTC 2024 using Jira 8.20.16#820016-sha1:9d11dbea5f4be3d4cc21f03a88dd11d8c8687422.