Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

can't start mysql 8 #352

Closed
beatlejuse opened this issue Dec 14, 2017 · 20 comments
Closed

can't start mysql 8 #352

beatlejuse opened this issue Dec 14, 2017 · 20 comments

Comments

@beatlejuse
Copy link

beatlejuse commented Dec 14, 2017 •
edited
Loading

i try to start mysql 8:
docker run --name some-mysql -e MYSQL_ROOT_PASSWORD=my-secret-pw -d mysql:8 (8.0/Dockerfile)
and see:

docker ps -a
CONTAINER ID        IMAGE     COMMAND                  CREATED             STATUS                         
2ddf38a6e978        mysql:8   "docker-entrypoint..."   20 seconds ago      Exited (1) 13 second s ago

docker logs 2ddf38a6e978 :
https://gist.github.com/beatlejuse/13d2dc8cf436f1e100577404367daff2
@tianon
Copy link
Member

tianon commented Dec 26, 2017

I can't reproduce: 😕

$ docker pull mysql:8
8: Pulling from library/mysql
f49cf87b52c1: Already exists 
78032de49d65: Already exists 
837546b20bc4: Already exists 
9b8316af6cc6: Already exists 
1056cf29b9f1: Already exists 
86f3913b029a: Already exists 
88ee0a67381b: Pull complete 
03bcb8298f42: Pull complete 
e7f60b590748: Pull complete 
0dca38a0e6a0: Pull complete 
87c6d825c7b1: Pull complete 
Digest: sha256:21cc59a132e029c688734a66b50bfa7e5b31af9f2f19267f463c247d51c67b5a
Status: Downloaded newer image for mysql:8

$ docker run --name some-mysql -e MYSQL_ROOT_PASSWORD=my-secret-pw -d mysql:8
87480de435355ebfab39ef83d7484c8330d6c98005837e24964e8b40dcea4eba

$ docker ps --filter name=some-mysql
CONTAINER ID        IMAGE               COMMAND                  CREATED             STATUS              PORTS               NAMES
87480de43535        mysql:8             "docker-entrypoint.s…"   18 seconds ago      Up 18 seconds       3306/tcp            some-mysql

$ docker logs some-mysql
Initializing database
2017-12-26T22:25:33.498466Z 0 [Note] Basedir set to /usr/
2017-12-26T22:25:33.498598Z 0 [Warning] The syntax '--symbolic-links/-s' is deprecated and will be removed in a future release
2017-12-26T22:25:33.499218Z 0 [Warning] You need to use --log-bin to make --log-slave-updates work.
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
2017-12-26T22:25:34.590437Z 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: b0d09975-ea8b-11e7-827a-0242ac120013.
2017-12-26T22:25:34.591928Z 0 [Warning] You have not provided a mandatory server-id. Servers in a replication topology must have unique server-ids. Please refer to the proper server start-up parameters documentation.
2017-12-26T22:25:34.594634Z 0 [Warning] Gtid table is not ready to be used. Table 'mysql.gtid_executed' cannot be opened.
2017-12-26T22:25:34.596289Z 5 [Warning] root@localhost is created with an empty password ! Please consider switching off the --initialize-insecure option.
2017-12-26T22:25:35.053157Z 5 [Warning] 'user' entry 'mysql.session@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:35.053178Z 5 [Warning] 'user' entry 'mysql.sys@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:35.053185Z 5 [Warning] 'user' entry 'root@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:35.053195Z 5 [Warning] 'db' entry 'performance_schema mysql.session@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:35.053199Z 5 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:35.053206Z 5 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:35.053251Z 5 [Warning] 'tables_priv' entry 'user mysql.session@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:35.053258Z 5 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
Database initialized
Initializing certificates
Generating a 2048 bit RSA private key
............................................................+++
.........+++
unable to write 'random state'
writing new private key to 'ca-key.pem'
-----
Generating a 2048 bit RSA private key
.....................................+++
.....................................+++
unable to write 'random state'
writing new private key to 'server-key.pem'
-----
Generating a 2048 bit RSA private key
.......+++
...+++
unable to write 'random state'
writing new private key to 'client-key.pem'
-----
Certificates initialized
MySQL init process in progress...
2017-12-26T22:25:37.593464Z 0 [Note] Basedir set to /usr/
2017-12-26T22:25:37.593584Z 0 [Warning] The syntax '--symbolic-links/-s' is deprecated and will be removed in a future release
2017-12-26T22:25:37.593623Z 0 [Note] --secure-file-priv is set to NULL. Operations related to importing and exporting data are disabled
2017-12-26T22:25:37.593644Z 0 [Note] /usr/sbin/mysqld (mysqld 8.0.3-rc-log) starting as process 89 ...
2017-12-26T22:25:37.595249Z 0 [Warning] No argument was provided to --log-bin, and --log-bin-index was not used; so replication may break when this MySQL server acts as a master and has his hostname changed!! Please use '--log-bin=87480de43535-bin' to avoid this problem.
2017-12-26T22:25:37.597344Z 0 [Note] InnoDB: Using Linux native AIO
2017-12-26T22:25:37.597405Z 0 [Note] Plugin 'FEDERATED' is disabled.
2017-12-26T22:25:37.598603Z 1 [Note] InnoDB: PUNCH HOLE support available
2017-12-26T22:25:37.598614Z 1 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2017-12-26T22:25:37.598616Z 1 [Note] InnoDB: Uses event mutexes
2017-12-26T22:25:37.598619Z 1 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2017-12-26T22:25:37.598621Z 1 [Note] InnoDB: Compressed tables use zlib 1.2.11
2017-12-26T22:25:37.598822Z 1 [Note] InnoDB: Number of pools: 1
2017-12-26T22:25:37.598871Z 1 [Note] InnoDB: Using CPU crc32 instructions
2017-12-26T22:25:37.599538Z 1 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
2017-12-26T22:25:37.604049Z 1 [Note] InnoDB: Completed initialization of buffer pool
2017-12-26T22:25:37.604822Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
2017-12-26T22:25:37.615587Z 1 [Note] InnoDB: Using 'tablespaces.open.1' max LSN: 13756914
2017-12-26T22:25:37.617281Z 1 [Note] InnoDB: Applying a batch of 0 redo log records ...
2017-12-26T22:25:37.617290Z 1 [Note] InnoDB: Apply batch completed!
2017-12-26T22:25:37.617810Z 1 [Note] InnoDB: Opened 2 existing undo tablespaces.
2017-12-26T22:25:37.621134Z 1 [Note] InnoDB: Creating shared tablespace for temporary tables
2017-12-26T22:25:37.621180Z 1 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2017-12-26T22:25:37.647858Z 1 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
2017-12-26T22:25:37.649165Z 1 [Note] InnoDB: Created 128 and tracked 128 new rollback segment(s) in the temporary tablespace. 128 are now active.
2017-12-26T22:25:37.649247Z 1 [Note] InnoDB: 8.0.3 started; log sequence number 15043776
2017-12-26T22:25:37.673848Z 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
2017-12-26T22:25:37.675436Z 0 [Note] InnoDB: Buffer pool(s) load completed at 171226 22:25:37
2017-12-26T22:25:37.681300Z 1 [Note] Found data dictionary with version 1
2017-12-26T22:25:37.693755Z 0 [Note] InnoDB: DDL log recovery : begin
2017-12-26T22:25:37.693783Z 0 [Note] InnoDB: DDL log recovery : end
2017-12-26T22:25:37.693846Z 0 [Note] InnoDB: Waiting for purge to start
2017-12-26T22:25:37.748413Z 0 [Warning] You have not provided a mandatory server-id. Servers in a replication topology must have unique server-ids. Please refer to the proper server start-up parameters documentation.
2017-12-26T22:25:37.750536Z 0 [Note] Found ca.pem, server-cert.pem and server-key.pem in data directory. Trying to enable SSL support using them.
2017-12-26T22:25:37.750704Z 0 [Warning] CA certificate ca.pem is self signed.
2017-12-26T22:25:37.763455Z 0 [Warning] 'user' entry 'mysql.session@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:37.763504Z 0 [Warning] 'user' entry 'mysql.sys@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:37.763523Z 0 [Warning] 'user' entry 'root@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:37.763552Z 0 [Warning] 'db' entry 'performance_schema mysql.session@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:37.763563Z 0 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:37.763580Z 0 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:37.766490Z 0 [Warning] 'tables_priv' entry 'user mysql.session@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:37.766517Z 0 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:37.772104Z 4 [Note] Event Scheduler: scheduler thread started with id 4
2017-12-26T22:25:37.772199Z 0 [Note] /usr/sbin/mysqld: ready for connections. Version: '8.0.3-rc-log'  socket: '/var/run/mysqld/mysqld.sock'  port: 0  MySQL Community Server (GPL)
Warning: Unable to load '/usr/share/zoneinfo/iso3166.tab' as time zone. Skipping it.
Warning: Unable to load '/usr/share/zoneinfo/leap-seconds.list' as time zone. Skipping it.
Warning: Unable to load '/usr/share/zoneinfo/zone.tab' as time zone. Skipping it.
2017-12-26T22:25:39.475276Z 9 [Warning] 'user' entry 'mysql.session@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:39.475343Z 9 [Warning] 'user' entry 'mysql.sys@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:39.475378Z 9 [Warning] 'user' entry 'root@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:39.475450Z 9 [Warning] 'db' entry 'performance_schema mysql.session@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:39.475477Z 9 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:39.475507Z 9 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:39.475797Z 9 [Warning] 'tables_priv' entry 'user mysql.session@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:39.475832Z 9 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.

2017-12-26T22:25:39.479834Z 0 [Note] Giving 1 client threads a chance to die gracefully
2017-12-26T22:25:39.479900Z 0 [Note] Shutting down slave threads
2017-12-26T22:25:39.479923Z 0 [Note] Event Scheduler: Killing the scheduler thread, thread id 4
2017-12-26T22:25:39.479940Z 0 [Note] Event Scheduler: Waiting for the scheduler thread to reply
2017-12-26T22:25:39.480115Z 0 [Note] Event Scheduler: Stopped
2017-12-26T22:25:39.480163Z 0 [Note] Forcefully disconnecting 0 remaining clients
2017-12-26T22:25:39.480182Z 0 [Note] Event Scheduler: Purging the queue. 0 events
2017-12-26T22:25:39.480381Z 0 [Note] InnoDB: FTS optimize thread exiting.
2017-12-26T22:25:39.583251Z 0 [Note] Binlog end
2017-12-26T22:25:39.586399Z 0 [Note] Shutting down plugin 'ngram'
2017-12-26T22:25:39.586446Z 0 [Note] Shutting down plugin 'BLACKHOLE'
2017-12-26T22:25:39.586464Z 0 [Note] Shutting down plugin 'ARCHIVE'
2017-12-26T22:25:39.586473Z 0 [Note] Shutting down plugin 'INNODB_CACHED_INDEXES'
2017-12-26T22:25:39.586484Z 0 [Note] Shutting down plugin 'INNODB_VIRTUAL'
2017-12-26T22:25:39.586492Z 0 [Note] Shutting down plugin 'INNODB_COLUMNS'
2017-12-26T22:25:39.586500Z 0 [Note] Shutting down plugin 'INNODB_TABLESPACES'
2017-12-26T22:25:39.586508Z 0 [Note] Shutting down plugin 'INNODB_INDEXES'
2017-12-26T22:25:39.586522Z 0 [Note] Shutting down plugin 'INNODB_TABLESTATS'
2017-12-26T22:25:39.586531Z 0 [Note] Shutting down plugin 'INNODB_TABLES'
2017-12-26T22:25:39.586542Z 0 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2017-12-26T22:25:39.586551Z 0 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2017-12-26T22:25:39.586560Z 0 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2017-12-26T22:25:39.586571Z 0 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2017-12-26T22:25:39.586580Z 0 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2017-12-26T22:25:39.586588Z 0 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2017-12-26T22:25:39.586599Z 0 [Note] Shutting down plugin 'INNODB_METRICS'
2017-12-26T22:25:39.586616Z 0 [Note] Shutting down plugin 'INNODB_TEMP_TABLE_INFO'
2017-12-26T22:25:39.586625Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2017-12-26T22:25:39.586646Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2017-12-26T22:25:39.586669Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2017-12-26T22:25:39.586684Z 0 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2017-12-26T22:25:39.586693Z 0 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2017-12-26T22:25:39.586702Z 0 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2017-12-26T22:25:39.586717Z 0 [Note] Shutting down plugin 'INNODB_CMPMEM'
2017-12-26T22:25:39.586734Z 0 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2017-12-26T22:25:39.586748Z 0 [Note] Shutting down plugin 'INNODB_CMP'
2017-12-26T22:25:39.586761Z 0 [Note] Shutting down plugin 'INNODB_TRX'
2017-12-26T22:25:39.586779Z 0 [Note] Shutting down plugin 'InnoDB'
2017-12-26T22:25:39.586833Z 0 [Note] InnoDB: Starting shutdown...
2017-12-26T22:25:39.687129Z 0 [Note] InnoDB: Dumping buffer pool(s) to /var/lib/mysql/ib_buffer_pool
2017-12-26T22:25:39.687881Z 0 [Note] InnoDB: Buffer pool(s) dump completed at 171226 22:25:39
2017-12-26T22:25:40.502074Z 0 [Note] InnoDB: Shutdown completed; log sequence number 26001471
2017-12-26T22:25:40.508123Z 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
2017-12-26T22:25:40.508189Z 0 [Note] Shutting down plugin 'MRG_MYISAM'
2017-12-26T22:25:40.508209Z 0 [Note] Shutting down plugin 'MyISAM'
2017-12-26T22:25:40.508241Z 0 [Note] Shutting down plugin 'CSV'
2017-12-26T22:25:40.508258Z 0 [Note] Shutting down plugin 'MEMORY'
2017-12-26T22:25:40.508271Z 0 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
2017-12-26T22:25:40.508338Z 0 [Note] Shutting down plugin 'TempTable'
2017-12-26T22:25:40.508356Z 0 [Note] Shutting down plugin 'sha2_cache_cleaner'
2017-12-26T22:25:40.508368Z 0 [Note] Shutting down plugin 'caching_sha2_password'
2017-12-26T22:25:40.508382Z 0 [Note] Shutting down plugin 'sha256_password'
2017-12-26T22:25:40.508394Z 0 [Note] Shutting down plugin 'mysql_native_password'
2017-12-26T22:25:40.508730Z 0 [Note] Shutting down plugin 'binlog'
2017-12-26T22:25:40.510338Z 0 [Note] /usr/sbin/mysqld: Shutdown complete


MySQL init process done. Ready for start up.

2017-12-26T22:25:40.737096Z 0 [Note] Basedir set to /usr/
2017-12-26T22:25:40.737193Z 0 [Warning] The syntax '--symbolic-links/-s' is deprecated and will be removed in a future release
2017-12-26T22:25:40.737221Z 0 [Note] --secure-file-priv is set to NULL. Operations related to importing and exporting data are disabled
2017-12-26T22:25:40.737237Z 0 [Note] /usr/sbin/mysqld (mysqld 8.0.3-rc-log) starting as process 1 ...
2017-12-26T22:25:40.738310Z 0 [Warning] No argument was provided to --log-bin, and --log-bin-index was not used; so replication may break when this MySQL server acts as a master and has his hostname changed!! Please use '--log-bin=87480de43535-bin' to avoid this problem.
2017-12-26T22:25:40.739054Z 0 [Note] InnoDB: Using Linux native AIO
2017-12-26T22:25:40.739092Z 0 [Note] Plugin 'FEDERATED' is disabled.
2017-12-26T22:25:40.739987Z 1 [Note] InnoDB: PUNCH HOLE support available
2017-12-26T22:25:40.739998Z 1 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2017-12-26T22:25:40.740002Z 1 [Note] InnoDB: Uses event mutexes
2017-12-26T22:25:40.740005Z 1 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2017-12-26T22:25:40.740009Z 1 [Note] InnoDB: Compressed tables use zlib 1.2.11
2017-12-26T22:25:40.740165Z 1 [Note] InnoDB: Number of pools: 1
2017-12-26T22:25:40.740213Z 1 [Note] InnoDB: Using CPU crc32 instructions
2017-12-26T22:25:40.740946Z 1 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
2017-12-26T22:25:40.746058Z 1 [Note] InnoDB: Completed initialization of buffer pool
2017-12-26T22:25:40.746967Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
2017-12-26T22:25:40.757784Z 1 [Note] InnoDB: Using 'tablespaces.open.2' max LSN: 15043821
2017-12-26T22:25:40.759484Z 1 [Note] InnoDB: Applying a batch of 0 redo log records ...
2017-12-26T22:25:40.759493Z 1 [Note] InnoDB: Apply batch completed!
2017-12-26T22:25:40.759870Z 1 [Note] InnoDB: Opened 2 existing undo tablespaces.
2017-12-26T22:25:40.764284Z 1 [Note] InnoDB: Creating shared tablespace for temporary tables
2017-12-26T22:25:40.764346Z 1 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2017-12-26T22:25:40.791709Z 1 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
2017-12-26T22:25:40.793046Z 1 [Note] InnoDB: Created 128 and tracked 128 new rollback segment(s) in the temporary tablespace. 128 are now active.
2017-12-26T22:25:40.793241Z 1 [Note] InnoDB: 8.0.3 started; log sequence number 26001471
2017-12-26T22:25:40.819953Z 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
2017-12-26T22:25:40.822219Z 0 [Note] InnoDB: Buffer pool(s) load completed at 171226 22:25:40
2017-12-26T22:25:40.827161Z 1 [Note] Found data dictionary with version 1
2017-12-26T22:25:40.838790Z 0 [Note] InnoDB: DDL log recovery : begin
2017-12-26T22:25:40.838818Z 0 [Note] InnoDB: DDL log recovery : end
2017-12-26T22:25:40.838890Z 0 [Note] InnoDB: Waiting for purge to start
2017-12-26T22:25:40.895533Z 0 [Warning] You have not provided a mandatory server-id. Servers in a replication topology must have unique server-ids. Please refer to the proper server start-up parameters documentation.
2017-12-26T22:25:40.932170Z 0 [Note] Found ca.pem, server-cert.pem and server-key.pem in data directory. Trying to enable SSL support using them.
2017-12-26T22:25:40.932439Z 0 [Warning] CA certificate ca.pem is self signed.
2017-12-26T22:25:40.934133Z 0 [Note] Server hostname (bind-address): '*'; port: 3306
2017-12-26T22:25:40.934168Z 0 [Note] IPv6 is available.
2017-12-26T22:25:40.934180Z 0 [Note]   - '::' resolves to '::';
2017-12-26T22:25:40.934220Z 0 [Note] Server socket created on IP: '::'.
2017-12-26T22:25:40.943113Z 0 [Warning] 'user' entry 'mysql.session@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:40.943145Z 0 [Warning] 'user' entry 'mysql.sys@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:40.943160Z 0 [Warning] 'user' entry 'root@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:40.943185Z 0 [Warning] 'db' entry 'performance_schema mysql.session@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:40.943195Z 0 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:40.943211Z 0 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:40.945723Z 0 [Warning] 'tables_priv' entry 'user mysql.session@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:40.945744Z 0 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
2017-12-26T22:25:40.949268Z 4 [Note] Event Scheduler: scheduler thread started with id 4
2017-12-26T22:25:40.949380Z 0 [Note] /usr/sbin/mysqld: ready for connections. Version: '8.0.3-rc-log'  socket: '/var/run/mysqld/mysqld.sock'  port: 3306  MySQL Community Server (GPL)

@tianon tianon closed this as completed Dec 26, 2017
@mavimo
Copy link

mavimo commented Jan 11, 2018

Same issue here:

database_1      | 2018-01-11T22:30:45.516876Z 0 [Note] Basedir set to /usr/
database_1      | 2018-01-11T22:30:45.517171Z 0 [Warning] The syntax '--symbolic-links/-s' is deprecated and will be removed in a future release
database_1      | 2018-01-11T22:30:45.517296Z 0 [Note] --secure-file-priv is set to NULL. Operations related to importing and exporting data are disabled
database_1      | 2018-01-11T22:30:45.517361Z 0 [Note] /usr/sbin/mysqld (mysqld 8.0.3-rc-log) starting as process 1 ...
database_1      | 2018-01-11T22:30:45.521458Z 0 [Warning] No argument was provided to --log-bin, and --log-bin-index was not used; so replication may break when this MySQL server acts as a master and has his hostname changed!! Please use '--log-bin=16c193685f2e-bin' to avoid this problem.
database_1      | 2018-01-11T22:30:45.526224Z 0 [Note] InnoDB: Using Linux native AIO
database_1      | 2018-01-11T22:30:45.526409Z 0 [Note] Plugin 'FEDERATED' is disabled.
database_1      | 2018-01-11T22:30:45.530388Z 1 [Note] Starting upgrade on data directory.
database_1      | 2018-01-11T22:30:45.530470Z 1 [Note] InnoDB: PUNCH HOLE support available
database_1      | 2018-01-11T22:30:45.530497Z 1 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
database_1      | 2018-01-11T22:30:45.530513Z 1 [Note] InnoDB: Uses event mutexes
database_1      | 2018-01-11T22:30:45.530530Z 1 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
database_1      | 2018-01-11T22:30:45.530545Z 1 [Note] InnoDB: Compressed tables use zlib 1.2.11
database_1      | 2018-01-11T22:30:45.531158Z 1 [Note] InnoDB: Number of pools: 1
database_1      | 2018-01-11T22:30:45.531362Z 1 [Note] InnoDB: Using CPU crc32 instructions
database_1      | 2018-01-11T22:30:45.534079Z 1 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
database_1      | libnuma: Warning: /sys not mounted or invalid. Assuming one node: No such file or directory
database_1      | mbind: Operation not permitted
database_1      | mbind: Operation not permitted
database_1      | mbind: Operation not permitted
database_1      | mbind: Operation not permitted
database_1      | 2018-01-11T22:30:45.564250Z 1 [Note] InnoDB: Completed initialization of buffer pool
database_1      | 2018-01-11T22:30:45.566968Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
database_1      | 2018-01-11T22:30:45.579515Z 1 [ERROR] InnoDB: Unsupported redo log format. The redo log was created before MySQL 5.7.9.
database_1      | 2018-01-11T22:30:45.579570Z 1 [ERROR] InnoDB: Plugin initialization aborted with error Generic error
database_1      | 2018-01-11T22:30:46.180217Z 1 [ERROR] Failed to initialize DD Storage Engine
database_1      | 2018-01-11T22:30:46.227261Z 0 [ERROR] Data Dictionary initialization failed.
database_1      | 2018-01-11T22:30:46.227304Z 0 [ERROR] Aborting
database_1      | 2018-01-11T22:30:46.227373Z 0 [Note] Binlog end
database_1      | 2018-01-11T22:30:46.227665Z 0 [Note] Shutting down plugin 'InnoDB'
database_1      | 2018-01-11T22:30:46.227704Z 0 [Note] Shutting down plugin 'MyISAM'
database_1      | 2018-01-11T22:30:46.227747Z 0 [Note] Shutting down plugin 'CSV'
database_1      | 2018-01-11T22:30:46.231316Z 0 [Note] /usr/sbin/mysqld: Shutdown complete
database_1      |

@yosifkit
Copy link
Member

@mavimo, can you give more information about how this was run? I don't see initialization, so this is with an already filled data directory? docker version. docker info, and docker run or docker-compose yaml. From the error message, it sounds like a duplicate of #303.

@beatlejuse
Copy link
Author

@yosifkit i deploy it to k8s v1.8.1 running on CoreOS 1520.8.0

apiVersion: extensions/v1beta1
kind: Deployment
metadata:
  name: mysql
  namespace: stm-support
spec:
  replicas: 1
  selector:
    matchLabels:
      app: mysql
  strategy:
    rollingUpdate:
      maxSurge: 1
      maxUnavailable: 0
    type: RollingUpdate
  template:
    metadata:
      labels:
        app: mysql
    spec:
      containers:
      - image: mysql:8.0.3
        name: mysql
        imagePullPolicy: Always
        env:
        - name: TZ
          value: "Europe/Moscow"
        - name: MYSQL_ROOT_PASSWORD
          value: "123456"
        - name: MYSQL_USER
          value: "root"
        ports:
        - name: mysql
          containerPort: 3306
          protocol: TCP
        resources:
          requests:
            cpu: 100m
            memory: 100Mi
          limits:
            cpu: 500m
            memory: 2500Mi

and pod constantly restarts:

kubectl -n stm-support get po
NAME                     READY     STATUS             RESTARTS   AGE
mysql-5f75cd4566-8h8kf   0/1       CrashLoopBackOff   94         7h

it's kubectl -n stm-support logs mysql-5f75cd4566-8h8kf :
https://gist.github.com/beatlejuse/c2348e7ae55fb3c5ec24ce3cad78cf56

@tianon
Copy link
Member

tianon commented Feb 15, 2018

@beatlejuse that's definitely an issue with either your environment or your configuration, and as such would be more appropriately posted to the Docker Community Forums, the Docker Community Slack, or Stack Overflow

If the error message was mbind: Operation not permitted, that's #303 and seccomp / NUMA related as @yosifkit noted. The error message in the logs you provided appears to be cut off, which you'll want to correct (and get the full log) when you post to the support forums so that folks can help diagnose better.

@beatlejuse
Copy link
Author

version 5 works in my environment normally.
other services also work fine.
the problem occurs only with mysql version 8.
if you believe that the problem is caused by a malfunction in the Docker, then you are mistaken.
I think the problem is with your doker image.
and log is full, it not cuted.
maybe you should take a closer look at the testing stage...

@tianon
Copy link
Member

tianon commented Feb 15, 2018

I can't reproduce the failure you've described:

$ docker pull mysql:8.0
8.0: Pulling from library/mysql
Digest: sha256:21cc59a132e029c688734a66b50bfa7e5b31af9f2f19267f463c247d51c67b5a
Status: Image is up to date for mysql:8.0

$ docker run -it --rm -e MYSQL_ROOT_PASSWORD=123456 mysql:8.0
Initializing database
2018-02-15T17:45:39.159863Z 0 [Note] Basedir set to /usr/
2018-02-15T17:45:39.160016Z 0 [Warning] The syntax '--symbolic-links/-s' is deprecated and will be removed in a future release
2018-02-15T17:45:39.161110Z 0 [Warning] You need to use --log-bin to make --log-slave-updates work.
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
2018-02-15T17:45:40.337451Z 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: 09ba35a2-1278-11e8-86ab-0242ac120008.
2018-02-15T17:45:40.339005Z 0 [Warning] You have not provided a mandatory server-id. Servers in a replication topology must have unique server-ids. Please refer to the proper server start-up parameters documentation.
2018-02-15T17:45:40.342549Z 0 [Warning] Gtid table is not ready to be used. Table 'mysql.gtid_executed' cannot be opened.
2018-02-15T17:45:40.344209Z 5 [Warning] root@localhost is created with an empty password ! Please consider switching off the --initialize-insecure option.
2018-02-15T17:45:40.781392Z 5 [Warning] 'user' entry 'mysql.session@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:40.781468Z 5 [Warning] 'user' entry 'mysql.sys@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:40.781504Z 5 [Warning] 'user' entry 'root@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:40.781557Z 5 [Warning] 'db' entry 'performance_schema mysql.session@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:40.781582Z 5 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:40.781612Z 5 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:40.781813Z 5 [Warning] 'tables_priv' entry 'user mysql.session@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:40.781839Z 5 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
Database initialized
Initializing certificates
Generating a 2048 bit RSA private key
........................................................................+++
.............................+++
unable to write 'random state'
writing new private key to 'ca-key.pem'
-----
Generating a 2048 bit RSA private key
.......+++
..........................................+++
unable to write 'random state'
writing new private key to 'server-key.pem'
-----
Generating a 2048 bit RSA private key
........................................................................................................+++
...........................+++
unable to write 'random state'
writing new private key to 'client-key.pem'
-----
Certificates initialized
MySQL init process in progress...
2018-02-15T17:45:43.591015Z 0 [Note] Basedir set to /usr/
2018-02-15T17:45:43.591111Z 0 [Warning] The syntax '--symbolic-links/-s' is deprecated and will be removed in a future release
2018-02-15T17:45:43.591142Z 0 [Note] --secure-file-priv is set to NULL. Operations related to importing and exporting data are disabled
2018-02-15T17:45:43.591168Z 0 [Note] /usr/sbin/mysqld (mysqld 8.0.3-rc-log) starting as process 89 ...
2018-02-15T17:45:43.592365Z 0 [Warning] No argument was provided to --log-bin, and --log-bin-index was not used; so replication may break when this MySQL server acts as a master and has his hostname changed!! Please use '--log-bin=8b6f372a5239-bin' to avoid this problem.
2018-02-15T17:45:43.594521Z 0 [Note] InnoDB: Using Linux native AIO
2018-02-15T17:45:43.594568Z 0 [Note] Plugin 'FEDERATED' is disabled.
2018-02-15T17:45:43.595517Z 1 [Note] InnoDB: PUNCH HOLE support available
2018-02-15T17:45:43.595528Z 1 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2018-02-15T17:45:43.595531Z 1 [Note] InnoDB: Uses event mutexes
2018-02-15T17:45:43.595533Z 1 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2018-02-15T17:45:43.595535Z 1 [Note] InnoDB: Compressed tables use zlib 1.2.11
2018-02-15T17:45:43.595671Z 1 [Note] InnoDB: Number of pools: 1
2018-02-15T17:45:43.595727Z 1 [Note] InnoDB: Using CPU crc32 instructions
2018-02-15T17:45:43.596402Z 1 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
2018-02-15T17:45:43.601886Z 1 [Note] InnoDB: Completed initialization of buffer pool
2018-02-15T17:45:43.602530Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
2018-02-15T17:45:43.615744Z 1 [Note] InnoDB: Using 'tablespaces.open.1' max LSN: 13757030
2018-02-15T17:45:43.618950Z 1 [Note] InnoDB: Applying a batch of 0 redo log records ...
2018-02-15T17:45:43.619003Z 1 [Note] InnoDB: Apply batch completed!
2018-02-15T17:45:43.621637Z 1 [Note] InnoDB: Opened 2 existing undo tablespaces.
2018-02-15T17:45:43.637916Z 1 [Note] InnoDB: Creating shared tablespace for temporary tables
2018-02-15T17:45:43.638081Z 1 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2018-02-15T17:45:43.674638Z 1 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
2018-02-15T17:45:43.677385Z 1 [Note] InnoDB: Created 128 and tracked 128 new rollback segment(s) in the temporary tablespace. 128 are now active.
2018-02-15T17:45:43.677661Z 1 [Note] InnoDB: 8.0.3 started; log sequence number 15043875
2018-02-15T17:45:43.709520Z 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
2018-02-15T17:45:43.711272Z 0 [Note] InnoDB: Buffer pool(s) load completed at 180215 17:45:43
2018-02-15T17:45:43.717395Z 1 [Note] Found data dictionary with version 1
2018-02-15T17:45:43.731516Z 0 [Note] InnoDB: DDL log recovery : begin
2018-02-15T17:45:43.731545Z 0 [Note] InnoDB: DDL log recovery : end
2018-02-15T17:45:43.731603Z 0 [Note] InnoDB: Waiting for purge to start
2018-02-15T17:45:43.789961Z 0 [Warning] You have not provided a mandatory server-id. Servers in a replication topology must have unique server-ids. Please refer to the proper server start-up parameters documentation.
2018-02-15T17:45:43.794858Z 0 [Note] Found ca.pem, server-cert.pem and server-key.pem in data directory. Trying to enable SSL support using them.
2018-02-15T17:45:43.795341Z 0 [Warning] CA certificate ca.pem is self signed.
2018-02-15T17:45:43.824741Z 0 [Warning] 'user' entry 'mysql.session@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:43.824826Z 0 [Warning] 'user' entry 'mysql.sys@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:43.824880Z 0 [Warning] 'user' entry 'root@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:43.824932Z 0 [Warning] 'db' entry 'performance_schema mysql.session@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:43.824953Z 0 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:43.824983Z 0 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:43.828758Z 0 [Warning] 'tables_priv' entry 'user mysql.session@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:43.828795Z 0 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:43.835115Z 4 [Note] Event Scheduler: scheduler thread started with id 4
2018-02-15T17:45:43.835457Z 0 [Note] /usr/sbin/mysqld: ready for connections. Version: '8.0.3-rc-log'  socket: '/var/run/mysqld/mysqld.sock'  port: 0  MySQL Community Server (GPL)
Warning: Unable to load '/usr/share/zoneinfo/iso3166.tab' as time zone. Skipping it.
Warning: Unable to load '/usr/share/zoneinfo/leap-seconds.list' as time zone. Skipping it.
Warning: Unable to load '/usr/share/zoneinfo/zone.tab' as time zone. Skipping it.
2018-02-15T17:45:45.465031Z 9 [Warning] 'user' entry 'mysql.session@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:45.465103Z 9 [Warning] 'user' entry 'mysql.sys@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:45.465144Z 9 [Warning] 'user' entry 'root@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:45.465205Z 9 [Warning] 'db' entry 'performance_schema mysql.session@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:45.465242Z 9 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:45.465278Z 9 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:45.465635Z 9 [Warning] 'tables_priv' entry 'user mysql.session@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:45.465685Z 9 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.

2018-02-15T17:45:45.470740Z 0 [Note] Giving 1 client threads a chance to die gracefully
2018-02-15T17:45:45.470816Z 0 [Note] Shutting down slave threads
2018-02-15T17:45:45.470844Z 0 [Note] Event Scheduler: Killing the scheduler thread, thread id 4
2018-02-15T17:45:45.470881Z 0 [Note] Event Scheduler: Waiting for the scheduler thread to reply
2018-02-15T17:45:45.471075Z 0 [Note] Event Scheduler: Stopped
2018-02-15T17:45:45.471136Z 0 [Note] Forcefully disconnecting 0 remaining clients
2018-02-15T17:45:45.471158Z 0 [Note] Event Scheduler: Purging the queue. 0 events
2018-02-15T17:45:45.471431Z 0 [Note] InnoDB: FTS optimize thread exiting.
2018-02-15T17:45:45.574002Z 0 [Note] Binlog end
2018-02-15T17:45:45.577646Z 0 [Note] Shutting down plugin 'ngram'
2018-02-15T17:45:45.577701Z 0 [Note] Shutting down plugin 'BLACKHOLE'
2018-02-15T17:45:45.577720Z 0 [Note] Shutting down plugin 'ARCHIVE'
2018-02-15T17:45:45.577739Z 0 [Note] Shutting down plugin 'INNODB_CACHED_INDEXES'
2018-02-15T17:45:45.577756Z 0 [Note] Shutting down plugin 'INNODB_VIRTUAL'
2018-02-15T17:45:45.577770Z 0 [Note] Shutting down plugin 'INNODB_COLUMNS'
2018-02-15T17:45:45.577780Z 0 [Note] Shutting down plugin 'INNODB_TABLESPACES'
2018-02-15T17:45:45.577794Z 0 [Note] Shutting down plugin 'INNODB_INDEXES'
2018-02-15T17:45:45.577807Z 0 [Note] Shutting down plugin 'INNODB_TABLESTATS'
2018-02-15T17:45:45.577819Z 0 [Note] Shutting down plugin 'INNODB_TABLES'
2018-02-15T17:45:45.577832Z 0 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2018-02-15T17:45:45.577846Z 0 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2018-02-15T17:45:45.577859Z 0 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2018-02-15T17:45:45.577872Z 0 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2018-02-15T17:45:45.577886Z 0 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2018-02-15T17:45:45.577899Z 0 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2018-02-15T17:45:45.577913Z 0 [Note] Shutting down plugin 'INNODB_METRICS'
2018-02-15T17:45:45.577926Z 0 [Note] Shutting down plugin 'INNODB_TEMP_TABLE_INFO'
2018-02-15T17:45:45.577940Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2018-02-15T17:45:45.577954Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2018-02-15T17:45:45.577967Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2018-02-15T17:45:45.577981Z 0 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2018-02-15T17:45:45.577996Z 0 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2018-02-15T17:45:45.578010Z 0 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2018-02-15T17:45:45.578024Z 0 [Note] Shutting down plugin 'INNODB_CMPMEM'
2018-02-15T17:45:45.578037Z 0 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2018-02-15T17:45:45.578051Z 0 [Note] Shutting down plugin 'INNODB_CMP'
2018-02-15T17:45:45.578068Z 0 [Note] Shutting down plugin 'INNODB_TRX'
2018-02-15T17:45:45.578085Z 0 [Note] Shutting down plugin 'InnoDB'
2018-02-15T17:45:45.578137Z 0 [Note] InnoDB: Starting shutdown...
2018-02-15T17:45:45.678413Z 0 [Note] InnoDB: Dumping buffer pool(s) to /var/lib/mysql/ib_buffer_pool
2018-02-15T17:45:45.679350Z 0 [Note] InnoDB: Buffer pool(s) dump completed at 180215 17:45:45
2018-02-15T17:45:46.494809Z 0 [Note] InnoDB: Shutdown completed; log sequence number 26001618
2018-02-15T17:45:46.499901Z 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
2018-02-15T17:45:46.499969Z 0 [Note] Shutting down plugin 'MRG_MYISAM'
2018-02-15T17:45:46.499997Z 0 [Note] Shutting down plugin 'MyISAM'
2018-02-15T17:45:46.500041Z 0 [Note] Shutting down plugin 'CSV'
2018-02-15T17:45:46.500062Z 0 [Note] Shutting down plugin 'MEMORY'
2018-02-15T17:45:46.500080Z 0 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
2018-02-15T17:45:46.500165Z 0 [Note] Shutting down plugin 'TempTable'
2018-02-15T17:45:46.500182Z 0 [Note] Shutting down plugin 'sha2_cache_cleaner'
2018-02-15T17:45:46.500203Z 0 [Note] Shutting down plugin 'caching_sha2_password'
2018-02-15T17:45:46.500225Z 0 [Note] Shutting down plugin 'sha256_password'
2018-02-15T17:45:46.500240Z 0 [Note] Shutting down plugin 'mysql_native_password'
2018-02-15T17:45:46.500585Z 0 [Note] Shutting down plugin 'binlog'
2018-02-15T17:45:46.501922Z 0 [Note] /usr/sbin/mysqld: Shutdown complete


MySQL init process done. Ready for start up.

2018-02-15T17:45:46.732440Z 0 [Note] Basedir set to /usr/
2018-02-15T17:45:46.732542Z 0 [Warning] The syntax '--symbolic-links/-s' is deprecated and will be removed in a future release
2018-02-15T17:45:46.732573Z 0 [Note] --secure-file-priv is set to NULL. Operations related to importing and exporting data are disabled
2018-02-15T17:45:46.732589Z 0 [Note] /usr/sbin/mysqld (mysqld 8.0.3-rc-log) starting as process 1 ...
2018-02-15T17:45:46.733864Z 0 [Warning] No argument was provided to --log-bin, and --log-bin-index was not used; so replication may break when this MySQL server acts as a master and has his hostname changed!! Please use '--log-bin=8b6f372a5239-bin' to avoid this problem.
2018-02-15T17:45:46.734707Z 0 [Note] InnoDB: Using Linux native AIO
2018-02-15T17:45:46.734755Z 0 [Note] Plugin 'FEDERATED' is disabled.
2018-02-15T17:45:46.735629Z 1 [Note] InnoDB: PUNCH HOLE support available
2018-02-15T17:45:46.735640Z 1 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2018-02-15T17:45:46.735643Z 1 [Note] InnoDB: Uses event mutexes
2018-02-15T17:45:46.735646Z 1 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2018-02-15T17:45:46.735649Z 1 [Note] InnoDB: Compressed tables use zlib 1.2.11
2018-02-15T17:45:46.735784Z 1 [Note] InnoDB: Number of pools: 1
2018-02-15T17:45:46.735846Z 1 [Note] InnoDB: Using CPU crc32 instructions
2018-02-15T17:45:46.736520Z 1 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
mbind: Operation not permitted
2018-02-15T17:45:46.741218Z 1 [Note] InnoDB: Completed initialization of buffer pool
2018-02-15T17:45:46.741837Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
2018-02-15T17:45:46.752688Z 1 [Note] InnoDB: Using 'tablespaces.open.2' max LSN: 15043920
2018-02-15T17:45:46.755247Z 1 [Note] InnoDB: Applying a batch of 0 redo log records ...
2018-02-15T17:45:46.755257Z 1 [Note] InnoDB: Apply batch completed!
2018-02-15T17:45:46.755683Z 1 [Note] InnoDB: Opened 2 existing undo tablespaces.
2018-02-15T17:45:46.759181Z 1 [Note] InnoDB: Creating shared tablespace for temporary tables
2018-02-15T17:45:46.759227Z 1 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2018-02-15T17:45:46.786511Z 1 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
2018-02-15T17:45:46.788088Z 1 [Note] InnoDB: Created 128 and tracked 128 new rollback segment(s) in the temporary tablespace. 128 are now active.
2018-02-15T17:45:46.788187Z 1 [Note] InnoDB: 8.0.3 started; log sequence number 26001618
2018-02-15T17:45:46.818299Z 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
2018-02-15T17:45:46.824779Z 1 [Note] Found data dictionary with version 1
2018-02-15T17:45:46.835222Z 0 [Note] InnoDB: DDL log recovery : begin
2018-02-15T17:45:46.835250Z 0 [Note] InnoDB: DDL log recovery : end
2018-02-15T17:45:46.835383Z 0 [Note] InnoDB: Waiting for purge to start
2018-02-15T17:45:46.890711Z 0 [Warning] You have not provided a mandatory server-id. Servers in a replication topology must have unique server-ids. Please refer to the proper server start-up parameters documentation.
2018-02-15T17:45:46.913807Z 0 [Note] Found ca.pem, server-cert.pem and server-key.pem in data directory. Trying to enable SSL support using them.
2018-02-15T17:45:46.914119Z 0 [Warning] CA certificate ca.pem is self signed.
2018-02-15T17:45:46.917022Z 0 [Note] Server hostname (bind-address): '*'; port: 3306
2018-02-15T17:45:46.917080Z 0 [Note] IPv6 is available.
2018-02-15T17:45:46.917095Z 0 [Note]   - '::' resolves to '::';
2018-02-15T17:45:46.917162Z 0 [Note] Server socket created on IP: '::'.
2018-02-15T17:45:46.930138Z 0 [Warning] 'user' entry 'mysql.session@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:46.930178Z 0 [Warning] 'user' entry 'mysql.sys@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:46.930193Z 0 [Warning] 'user' entry 'root@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:46.930219Z 0 [Warning] 'db' entry 'performance_schema mysql.session@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:46.930231Z 0 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:46.930247Z 0 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:46.933386Z 0 [Warning] 'tables_priv' entry 'user mysql.session@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:46.933409Z 0 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
2018-02-15T17:45:46.938082Z 4 [Note] Event Scheduler: scheduler thread started with id 4
2018-02-15T17:45:46.938216Z 0 [Note] /usr/sbin/mysqld: ready for connections. Version: '8.0.3-rc-log'  socket: '/var/run/mysqld/mysqld.sock'  port: 3306  MySQL Community Server (GPL)
2018-02-15T17:45:47.819669Z 0 [Note] InnoDB: Buffer pool(s) load completed at 180215 17:45:47

(Note the mbind messages due to NUMA as previously discussed in this issue you're hijacking, which as you can see, don't actually stop the server from starting up successfully.)

If you can come up with a set of simple steps which reliably reproduce the failure in a clean environment, we'd be happy to take a look at whether there's anything we can change in the image, but even then it's more likely to be an issue with upstream MySQL which would need to be filed in their bug tracker.

@aakldey
Copy link

aakldey commented Feb 23, 2018

got the same problem, it was working good some time ago, but now it says mbind: Operation not permitted

@tianon
Copy link
Member

tianon commented Feb 23, 2018

@aakldey as noted in #352 (comment):

If the error message was mbind: Operation not permitted, that's #303 and seccomp / NUMA related as @yosifkit noted. The error message in the logs you provided appears to be cut off, which you'll want to correct (and get the full log) when you post to the support forums so that folks can help diagnose better.

You will need to adjust your container's seccomp profile to allow NUMA (set_mempolicy).

@aakldey
Copy link

aakldey commented Feb 23, 2018

I tried:
"SecurityOpt": [
"seccomp:set_mempolicy"
],
or
"SecurityOpt": [
"seccomp:unconfined"
],
but still it's not working
while it was working 2 weeks ago without it.
I have latest docker version

@codeblooded
Copy link

This is now blocking my progress with mysql

@johncmunson
Copy link

All of a sudden I started getting these mbind errors as well. For me, the solution was locking down the version of the mysql docker image. I changed my docker-compose.yml from...

services:
  mysqldb:
    image: mysql

to...

services:
  mysqldb:
    image: mysql:5.7.22

@gianpietro1
Copy link

gianpietro1 commented Apr 23, 2018 •
edited
Loading

It seems that 'latest' used to point to 5.x, but 3 days ago it changed to '8.x' as reported here, and "mbind: Operation not permitted" messages started appearing, in my case whenever trying to create a database from another container.
My quick workaround was to use 5.7.22 just as @johncmunson did.
Any pointer on what changed between versions that lead to this behavior?

@ltangvald
Copy link
Collaborator

Are you sure the mbind error message is the cause of your problem? It's a known issue with running MySQL 8.0 (which the latest tag now points to) in Docker, but it's not a fatal error.

@codeblooded
Copy link

You’re right. Maybe it’s more than one thing. Unfortunately the mbind issue is a blocker, because it’s not publishing MySQL on the hosts ports, so I can’t really access and debug it. I’m assuming it still fails to start, which also inhibits debugging, because by the time I run a command on docker compose to access the service, it will have died.

@codeblooded
Copy link

codeblooded commented Apr 23, 2018 •
edited
Loading

Ok, I finally got it to work using 5.7 🎉

I made a mistake testing and I pulled the image like so:

$ docker pull mysql:5.7

And then ran it like this:

$ docker run -p 3306:3306 -e MYSQL_ROOT_PASSWORD=secret -d mysql

Which pulled v8 because the latest tag changed. So switching to this fixed it:

$ docker run -p 3306:3306 -e MYSQL_ROOT_PASSWORD=secret -d mysql:5.7

@pscheit
Copy link

pscheit commented Jun 10, 2018

How can this be closed? The mysql version 8 does not work in docker?

@yosifkit
Copy link
Member

@pscheit, there has been nothing in this thread to show that the mysql:8 image is bad.

If you are running the mysql:8 image with a database created with 5.7, then you need to run mysql_upgrade like this PR might add: #416.

If you have another problem that you can give a concise reproducer, then please open an issue.

@juicyarts
Copy link

juicyarts commented Aug 23, 2018 •
edited
Loading

for those having similar issues while using volumes via docker compose: It looks like the mysql service is not able to "remount" the volumes it used to mount before (with 5.7~).
Using a compose file like this:

version: '3'

services:
  database:
    image: mysql:latest
    volumes:
      - ./mysql/database:/var/lib/mysql
      - ./mysql/dump:/docker-entrypoint-initdb.d/
    ports:
      - 3306:3306
    environment:
       - MYSQL_ROOT_PASSWORD
       - MYSQL_DATABASE
       - MYSQL_USER
       - MYSQL_PASSWORD

When the mounted mysql/database folder contains content from 5.7~ the mysql services crashes:

Creating sms-wp_database_1 ... done
Attaching to sms-wp_database_1
database_1   | 2018-08-23T11:04:45.629269Z 0 [Warning] [MY-011070] [Server] 'Disabling symbolic links using --skip-symbolic-links (o
r equivalent) is the default. Consider not using this option as it' is deprecated and will be removed in a future release.
database_1   | 2018-08-23T11:04:45.630124Z 0 [System] [MY-010116] [Server] /usr/sbin/mysqld (mysqld 8.0.12) starting as process 1
database_1   | mbind: Operation not permitted
database_1   | mbind: Operation not permitted
database_1   | 2018-08-23T11:04:48.724801Z 1 [ERROR] [MY-012526] [InnoDB] InnoDB: Upgrade after a crash is not supported. This redo
log was created with MySQL 5.7.21. Please follow the instructions at http://dev.mysql.com/doc/refman/8.0/en/upgrading.html
database_1   | 2018-08-23T11:04:48.725042Z 1 [ERROR] [MY-012930] [InnoDB] InnoDB: Plugin initialization aborted with error Generic e
rror.
database_1   | 2018-08-23T11:04:49.231777Z 1 [ERROR] [MY-011013] [Server] Failed to initialize DD Storage Engine.
database_1   | 2018-08-23T11:04:49.237592Z 0 [ERROR] [MY-010020] [Server] Data Dictionary initialization failed.
database_1   | 2018-08-23T11:04:49.237721Z 0 [ERROR] [MY-010119] [Server] Aborting
database_1   | 2018-08-23T11:04:49.243089Z 0 [System] [MY-010910] [Server] /usr/sbin/mysqld: Shutdown complete (mysqld 8.0.12)  MySQ
L Community Server - GPL.
sms-wp_database_1 exited with code 1

afaik the message mbind: Operation not permitted is not the issue here since its "just" a warning. see #303

My workaround for this was to backup the previous database using 5.7 and use the dump to recover the database (using the other mounted folder mysql/dump).

@pxsocs pxsocs mentioned this issue Jun 14, 2019
Closed
@rabinpoudyal
Copy link

Uploading image.png…
Disable this and it will work

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests