Skip to content
This repository has been archived by the owner. It is now read-only.

MySQL server has gone away #47335

Closed
craigcarnell opened this issue Dec 24, 2015 · 16 comments
Closed

MySQL server has gone away #47335

craigcarnell opened this issue Dec 24, 2015 · 16 comments

Comments

@craigcarnell
Copy link

@craigcarnell craigcarnell commented Dec 24, 2015

Using percona-server

mysqld --version:

mysqld Ver 5.6.25-73.1 for osx10.11 on x86_64 (Homebrew)

Frequently get this trying to run Magento 2 setup. Using PHP 7.0.1

@craigcarnell

This comment has been minimized.

Copy link
Author

@craigcarnell craigcarnell commented Dec 24, 2015

Solved by max_allowed_packet=100M

@craigcarnell

This comment has been minimized.

Copy link
Author

@craigcarnell craigcarnell commented Jan 4, 2016

Still happens for me even with 500M, seems random. Percona server is latest, edited /etc/my.cnf

@craigcarnell

This comment has been minimized.

Copy link
Author

@craigcarnell craigcarnell commented Jan 5, 2016

Same issue with mysql 5.7

@craigcarnell

This comment has been minimized.

Copy link
Author

@craigcarnell craigcarnell commented Jan 15, 2016

Anybody - this is a big problem!

@DomT4

This comment has been minimized.

Copy link
Contributor

@DomT4 DomT4 commented Jan 15, 2016

You really need to provide more detail here. Saying it has "gone away" isn't particularly easy to debug.

@craigcarnell

This comment has been minimized.

Copy link
Author

@craigcarnell craigcarnell commented Jan 15, 2016

@DomT4 How can I provide more info? Thanks

@MikeMcQuaid

This comment has been minimized.

Copy link
Member

@MikeMcQuaid MikeMcQuaid commented Jan 15, 2016

@craigcarnell To help us debug this issue can you explain:

  • What you were trying to do (and why)
  • What happened
  • What you expected to happen
  • Step-by-step reproduction instructions (with as minimal input data as possible)
@MikeMcQuaid

This comment has been minimized.

Copy link
Member

@MikeMcQuaid MikeMcQuaid commented Jan 15, 2016

Also, sorry but you have not followed the requested steps on the Troubleshooting page: https://github.com/Homebrew/homebrew/blob/master/share/doc/homebrew/Troubleshooting.md#troubleshooting

Please follow (all of) these steps and post the information here so we can help you with your problem.

Thanks!

@craigcarnell

This comment has been minimized.

Copy link
Author

@craigcarnell craigcarnell commented Jan 18, 2016

@MikeMcQuaid I am using php 7 to run Magento.

It regularly crashes mysql

Homebrew/homebrew-php#2794

@nijikon

This comment has been minimized.

Copy link
Contributor

@nijikon nijikon commented Jan 19, 2016

@craigcarnell Can you provide your mysql logs?

@craigcarnell

This comment has been minimized.

Copy link
Author

@craigcarnell craigcarnell commented Jan 21, 2016

@nijikon

160121 09:18:33 mysqld_safe Starting mysqld daemon with databases from /usr/local/var/mysql
2016-01-21T09:18:33.870845Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
2016-01-21T09:18:33.871413Z 0 [Warning] 'NO_ZERO_DATE', 'NO_ZERO_IN_DATE' and 'ERROR_FOR_DIVISION_BY_ZERO' sql modes should be used with strict mode. They will be merged with strict mode in a future release.
2016-01-21T09:18:33.871430Z 0 [Warning] 'NO_AUTO_CREATE_USER' sql mode was not set.
2016-01-21T09:18:33.871528Z 0 [Warning] Insecure configuration for --secure-file-priv: Current value does not restrict location of generated files. Consider setting it to a valid, non-empty path.
2016-01-21T09:18:33.871773Z 0 [Note] /usr/local/Cellar/mysql/5.7.10/bin/mysqld (mysqld 5.7.10) starting as process 666 ...
2016-01-21T09:18:33.917852Z 0 [Warning] Setting lower_case_table_names=2 because file system for /usr/local/var/mysql/ is case insensitive
2016-01-21T09:18:33.931588Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2016-01-21T09:18:33.931618Z 0 [Note] InnoDB: Uses event mutexes
2016-01-21T09:18:33.931626Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2016-01-21T09:18:33.931634Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.3
2016-01-21T09:18:33.932131Z 0 [Note] InnoDB: Number of pools: 1
2016-01-21T09:18:33.932591Z 0 [Note] InnoDB: Using CPU crc32 instructions
2016-01-21T09:18:33.977869Z 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
2016-01-21T09:18:34.113795Z 0 [Note] InnoDB: Completed initialization of buffer pool
2016-01-21T09:18:34.130871Z 0 [Note] InnoDB: Highest supported file format is Barracuda.
2016-01-21T09:18:34.283611Z 0 [Note] InnoDB: Log scan progressed past the checkpoint lsn 19711672592
2016-01-21T09:18:34.283637Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 19711672601
2016-01-21T09:18:34.283813Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 19711672601
2016-01-21T09:18:34.283829Z 0 [Note] InnoDB: Database was not shutdown normally!
2016-01-21T09:18:34.283836Z 0 [Note] InnoDB: Starting crash recovery.
2016-01-21T09:18:51.185626Z 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
2016-01-21T09:18:51.185660Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables
2016-01-21T09:18:51.185845Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2016-01-21T09:18:51.442335Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
2016-01-21T09:18:51.444020Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active.
2016-01-21T09:18:51.444044Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active.
2016-01-21T09:18:51.444289Z 0 [Note] InnoDB: Waiting for purge to start
2016-01-21T09:18:51.494622Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 17378ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-21T09:18:51.494647Z 0 [Note] InnoDB: 5.7.10 started; log sequence number 19711672601
2016-01-21T09:18:51.494813Z 0 [Note] InnoDB: Loading buffer pool(s) from /usr/local/var/mysql/ib_buffer_pool
2016-01-21T09:18:51.494820Z 0 [Note] InnoDB: not started
2016-01-21T09:18:51.495111Z 0 [Note] Plugin 'FEDERATED' is disabled.
2016-01-21T09:18:51.546954Z 0 [Note] InnoDB: Buffer pool(s) load completed at 160121  9:18:51
2016-01-21T09:18:51.558969Z 0 [Note] Found ca.pem, server-cert.pem and server-key.pem in data directory. Trying to enable SSL support using them.
2016-01-21T09:18:51.558997Z 0 [Note] Skipping generation of SSL certificates as certificate files are present in data directory.
2016-01-21T09:18:51.567102Z 0 [Warning] CA certificate ca.pem is self signed.
2016-01-21T09:18:51.567206Z 0 [Note] Skipping generation of RSA key pair as key files are present in data directory.
2016-01-21T09:18:51.708566Z 0 [Note] Server hostname (bind-address): '127.0.0.1'; port: 3306
2016-01-21T09:18:51.709160Z 0 [Note]   - '127.0.0.1' resolves to '127.0.0.1';
2016-01-21T09:18:51.709197Z 0 [Note] Server socket created on IP: '127.0.0.1'.
2016-01-21T09:18:53.298034Z 0 [Note] Event Scheduler: Loaded 0 events
2016-01-21T09:18:53.298227Z 0 [Note] /usr/local/Cellar/mysql/5.7.10/bin/mysqld: ready for connections.
Version: '5.7.10'  socket: '/tmp/mysql.sock'  port: 3306  Homebrew
2016-01-21T09:55:27.971441Z 0 [Warning] File Descriptor 1228 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:28.053069Z 0 [Warning] File Descriptor 1228 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:28.129336Z 0 [Warning] File Descriptor 1228 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:28.129492Z 0 [Warning] File Descriptor 1228 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:28.188654Z 0 [Warning] File Descriptor 1228 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:28.189591Z 0 [Warning] File Descriptor 1228 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:28.279268Z 0 [Warning] File Descriptor 1228 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:28.288467Z 0 [Warning] File Descriptor 1228 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:46.859865Z 0 [Warning] File Descriptor 1238 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:46.979326Z 0 [Warning] File Descriptor 1238 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:46.993378Z 0 [Warning] File Descriptor 1238 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:47.065052Z 0 [Warning] File Descriptor 1238 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:47.095210Z 0 [Warning] File Descriptor 1238 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:50.491113Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:50.637737Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:50.835150Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:50.909215Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:50.990091Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:51.023822Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:51.076600Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:51.115306Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:51.191857Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:51.286847Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:51.355395Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:51.440363Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:51.508658Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:51.587352Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:51.651756Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:51.733311Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:51.806246Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:51.843068Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:51.911140Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:51.923302Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:51.987548Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T09:55:52.002327Z 0 [Warning] File Descriptor 1173 exceedeed FD_SETSIZE=1024
2016-01-21T10:30:34.469170Z 0 [Warning] File Descriptor 1196 exceedeed FD_SETSIZE=1024
2016-01-21T10:33:37.009094Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 58207ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-21T11:13:58.012686Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 70006ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-21T11:38:02.904857Z 0 [Warning] File Descriptor 1198 exceedeed FD_SETSIZE=1024
2016-01-21T15:02:16.006222Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 40595ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-21T15:05:06.660240Z 0 [Warning] File Descriptor 1185 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:06.786718Z 0 [Warning] File Descriptor 1185 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:06.793268Z 0 [Warning] File Descriptor 1185 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:07.546767Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:07.547718Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:07.659316Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:07.670048Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:07.833125Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:07.835013Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:07.915704Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:07.925983Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:08.007469Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:08.198460Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:08.265290Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:08.359381Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:08.385313Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:08.484469Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:08.512308Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:08.580362Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:08.600499Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:08.643690Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:08.718083Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:08.778537Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:08.886097Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:09.032244Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:09.177304Z 0 [Warning] File Descriptor 1197 exceedeed FD_SETSIZE=1024
2016-01-21T15:05:28.224262Z 0 [Note] Giving 0 client threads a chance to die gracefully
2016-01-21T15:05:28.224287Z 0 [Note] Shutting down slave threads
2016-01-21T15:05:28.235337Z 0 [Note] Forcefully disconnecting 0 remaining clients
2016-01-21T15:05:28.235360Z 0 [Note] Event Scheduler: Purging the queue. 0 events
2016-01-21T15:05:28.235403Z 0 [Note] Binlog end
2016-01-21T15:05:28.312603Z 0 [Note] Shutting down plugin 'ngram'
2016-01-21T15:05:28.312637Z 0 [Note] Shutting down plugin 'partition'
2016-01-21T15:05:28.312647Z 0 [Note] Shutting down plugin 'BLACKHOLE'
2016-01-21T15:05:28.312657Z 0 [Note] Shutting down plugin 'ARCHIVE'
2016-01-21T15:05:28.312663Z 0 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
2016-01-21T15:05:28.312715Z 0 [Note] Shutting down plugin 'MRG_MYISAM'
2016-01-21T15:05:28.318384Z 0 [Note] Shutting down plugin 'MyISAM'
2016-01-21T15:05:28.318434Z 0 [Note] Shutting down plugin 'INNODB_SYS_VIRTUAL'
2016-01-21T15:05:28.318444Z 0 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
2016-01-21T15:05:28.318449Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
2016-01-21T15:05:28.318454Z 0 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
2016-01-21T15:05:28.318459Z 0 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
2016-01-21T15:05:28.318464Z 0 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
2016-01-21T15:05:28.318468Z 0 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
2016-01-21T15:05:28.318473Z 0 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
2016-01-21T15:05:28.318478Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
2016-01-21T15:05:28.318482Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
2016-01-21T15:05:28.318487Z 0 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2016-01-21T15:05:28.318491Z 0 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2016-01-21T15:05:28.318496Z 0 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2016-01-21T15:05:28.318500Z 0 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2016-01-21T15:05:28.318505Z 0 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2016-01-21T15:05:28.318510Z 0 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2016-01-21T15:05:28.318515Z 0 [Note] Shutting down plugin 'INNODB_METRICS'
2016-01-21T15:05:28.318519Z 0 [Note] Shutting down plugin 'INNODB_TEMP_TABLE_INFO'
2016-01-21T15:05:28.318524Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2016-01-21T15:05:28.318529Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2016-01-21T15:05:28.318534Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2016-01-21T15:05:28.318538Z 0 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2016-01-21T15:05:28.318543Z 0 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2016-01-21T15:05:28.318547Z 0 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2016-01-21T15:05:28.318552Z 0 [Note] Shutting down plugin 'INNODB_CMPMEM'
2016-01-21T15:05:28.318557Z 0 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2016-01-21T15:05:28.318562Z 0 [Note] Shutting down plugin 'INNODB_CMP'
2016-01-21T15:05:28.318566Z 0 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
2016-01-21T15:05:28.318571Z 0 [Note] Shutting down plugin 'INNODB_LOCKS'
2016-01-21T15:05:28.318576Z 0 [Note] Shutting down plugin 'INNODB_TRX'
2016-01-21T15:05:28.318581Z 0 [Note] Shutting down plugin 'InnoDB'
2016-01-21T15:05:28.318634Z 0 [Note] InnoDB: FTS optimize thread exiting.
2016-01-21T15:05:28.318684Z 0 [Note] InnoDB: Starting shutdown...
2016-01-21T15:05:28.418924Z 0 [Note] InnoDB: Dumping buffer pool(s) to /usr/local/var/mysql/ib_buffer_pool
2016-01-21T15:05:28.435309Z 0 [Note] InnoDB: Buffer pool(s) dump completed at 160121 15:05:28
160121 15:05:28 mysqld_safe A mysqld process already exists
2016-01-21T15:05:29.866946Z 0 [Note] InnoDB: Shutdown completed; log sequence number 19970918590
2016-01-21T15:05:29.882895Z 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
2016-01-21T15:05:29.882922Z 0 [Note] Shutting down plugin 'MEMORY'
2016-01-21T15:05:29.883150Z 0 [Note] Shutting down plugin 'CSV'
2016-01-21T15:05:29.883164Z 0 [Note] Shutting down plugin 'sha256_password'
2016-01-21T15:05:29.883169Z 0 [Note] Shutting down plugin 'mysql_native_password'
2016-01-21T15:05:29.883358Z 0 [Note] Shutting down plugin 'binlog'
2016-01-21T15:05:29.897369Z 0 [Note] /usr/local/Cellar/mysql/5.7.10/bin/mysqld: Shutdown complete

160121 15:05:38 mysqld_safe Starting mysqld daemon with databases from /usr/local/var/mysql
2016-01-21T15:05:39.189233Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
2016-01-21T15:05:39.189402Z 0 [Warning] 'NO_ZERO_DATE', 'NO_ZERO_IN_DATE' and 'ERROR_FOR_DIVISION_BY_ZERO' sql modes should be used with strict mode. They will be merged with strict mode in a future release.
2016-01-21T15:05:39.189413Z 0 [Warning] 'NO_AUTO_CREATE_USER' sql mode was not set.
2016-01-21T15:05:39.189499Z 0 [Warning] Insecure configuration for --secure-file-priv: Current value does not restrict location of generated files. Consider setting it to a valid, non-empty path.
2016-01-21T15:05:39.189562Z 0 [Note] /usr/local/Cellar/mysql/5.7.10/bin/mysqld (mysqld 5.7.10) starting as process 25708 ...
2016-01-21T15:05:39.230460Z 0 [Warning] Setting lower_case_table_names=2 because file system for /usr/local/var/mysql/ is case insensitive
2016-01-21T15:05:39.232047Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2016-01-21T15:05:39.232067Z 0 [Note] InnoDB: Uses event mutexes
2016-01-21T15:05:39.232072Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2016-01-21T15:05:39.232079Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.3
2016-01-21T15:05:39.232431Z 0 [Note] InnoDB: Number of pools: 1
2016-01-21T15:05:39.232539Z 0 [Note] InnoDB: Using CPU crc32 instructions
2016-01-21T15:05:39.243995Z 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
2016-01-21T15:05:39.257211Z 0 [Note] InnoDB: Completed initialization of buffer pool
2016-01-21T15:05:39.278491Z 0 [Note] InnoDB: Highest supported file format is Barracuda.
2016-01-21T15:05:40.625374Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables
2016-01-21T15:05:40.625503Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2016-01-21T15:05:40.749790Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
2016-01-21T15:05:40.751056Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active.
2016-01-21T15:05:40.751087Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active.
2016-01-21T15:05:40.751284Z 0 [Note] InnoDB: Waiting for purge to start
2016-01-21T15:05:40.803358Z 0 [Note] InnoDB: 5.7.10 started; log sequence number 19970918590
2016-01-21T15:05:40.803550Z 0 [Note] InnoDB: Loading buffer pool(s) from /usr/local/var/mysql/ib_buffer_pool
2016-01-21T15:05:40.803678Z 0 [Note] InnoDB: not started
2016-01-21T15:05:40.803859Z 0 [Note] Plugin 'FEDERATED' is disabled.
2016-01-21T15:05:40.879682Z 0 [Note] InnoDB: Buffer pool(s) load completed at 160121 15:05:40
2016-01-21T15:05:41.058732Z 0 [Note] Found ca.pem, server-cert.pem and server-key.pem in data directory. Trying to enable SSL support using them.
2016-01-21T15:05:41.058748Z 0 [Note] Skipping generation of SSL certificates as certificate files are present in data directory.
2016-01-21T15:05:41.091869Z 0 [Warning] CA certificate ca.pem is self signed.
2016-01-21T15:05:41.091945Z 0 [Note] Skipping generation of RSA key pair as key files are present in data directory.
2016-01-21T15:05:41.221967Z 0 [Note] Server hostname (bind-address): '127.0.0.1'; port: 3306
2016-01-21T15:05:41.222539Z 0 [Note]   - '127.0.0.1' resolves to '127.0.0.1';
2016-01-21T15:05:41.222578Z 0 [Note] Server socket created on IP: '127.0.0.1'.
2016-01-21T15:05:43.208832Z 0 [Note] Event Scheduler: Loaded 0 events
2016-01-21T15:05:43.209047Z 0 [Note] /usr/local/Cellar/mysql/5.7.10/bin/mysqld: ready for connections.
Version: '5.7.10'  socket: '/tmp/mysql.sock'  port: 3306  Homebrew
2016-01-21T15:37:00.006344Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 64944ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-21T15:59:06.007206Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 105166ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
160121 16:01:31 mysqld_safe Starting mysqld daemon with databases from /usr/local/var/mysql
2016-01-21T16:01:33.266914Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
2016-01-21T16:01:33.267405Z 0 [Warning] 'NO_ZERO_DATE', 'NO_ZERO_IN_DATE' and 'ERROR_FOR_DIVISION_BY_ZERO' sql modes should be used with strict mode. They will be merged with strict mode in a future release.
2016-01-21T16:01:33.267415Z 0 [Warning] 'NO_AUTO_CREATE_USER' sql mode was not set.
2016-01-21T16:01:33.267499Z 0 [Warning] Insecure configuration for --secure-file-priv: Current value does not restrict location of generated files. Consider setting it to a valid, non-empty path.
2016-01-21T16:01:33.267669Z 0 [Note] /usr/local/Cellar/mysql/5.7.10/bin/mysqld (mysqld 5.7.10) starting as process 521 ...
2016-01-21T16:01:33.274239Z 0 [Warning] Setting lower_case_table_names=2 because file system for /usr/local/var/mysql/ is case insensitive
2016-01-21T16:01:33.276738Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2016-01-21T16:01:33.276763Z 0 [Note] InnoDB: Uses event mutexes
2016-01-21T16:01:33.276771Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2016-01-21T16:01:33.276779Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.3
2016-01-21T16:01:33.277249Z 0 [Note] InnoDB: Number of pools: 1
2016-01-21T16:01:33.277774Z 0 [Note] InnoDB: Using CPU crc32 instructions
2016-01-21T16:01:33.583654Z 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
2016-01-21T16:01:33.737445Z 0 [Note] InnoDB: Completed initialization of buffer pool
2016-01-21T16:01:33.767772Z 0 [Note] InnoDB: Highest supported file format is Barracuda.
2016-01-21T16:01:33.785103Z 0 [Note] InnoDB: Log scan progressed past the checkpoint lsn 19971126284
2016-01-21T16:01:33.785887Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 19971126293
2016-01-21T16:01:33.786769Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 19971126293
2016-01-21T16:01:33.786804Z 0 [Note] InnoDB: Database was not shutdown normally!
2016-01-21T16:01:33.786819Z 0 [Note] InnoDB: Starting crash recovery.
2016-01-21T16:01:54.211369Z 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
2016-01-21T16:01:54.211394Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables
2016-01-21T16:01:54.211512Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2016-01-21T16:01:54.362597Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
2016-01-21T16:01:54.363554Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active.
2016-01-21T16:01:54.363570Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active.
2016-01-21T16:01:54.363799Z 0 [Note] InnoDB: Waiting for purge to start
2016-01-21T16:01:54.413927Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 20651ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-21T16:01:54.413981Z 0 [Note] InnoDB: 5.7.10 started; log sequence number 19971126293
2016-01-21T16:01:54.414144Z 0 [Note] InnoDB: Loading buffer pool(s) from /usr/local/var/mysql/ib_buffer_pool
2016-01-21T16:01:54.414159Z 0 [Note] InnoDB: not started
2016-01-21T16:01:54.457093Z 0 [Note] Plugin 'FEDERATED' is disabled.
2016-01-21T16:01:56.282699Z 0 [Note] Found ca.pem, server-cert.pem and server-key.pem in data directory. Trying to enable SSL support using them.
2016-01-21T16:01:56.282716Z 0 [Note] Skipping generation of SSL certificates as certificate files are present in data directory.
2016-01-21T16:01:56.413228Z 0 [Warning] CA certificate ca.pem is self signed.
2016-01-21T16:01:56.413304Z 0 [Note] Skipping generation of RSA key pair as key files are present in data directory.
2016-01-21T16:01:56.691276Z 0 [Note] Server hostname (bind-address): '127.0.0.1'; port: 3306
2016-01-21T16:01:56.691944Z 0 [Note]   - '127.0.0.1' resolves to '127.0.0.1';
2016-01-21T16:01:56.692011Z 0 [Note] Server socket created on IP: '127.0.0.1'.
2016-01-21T16:02:11.567486Z 0 [Note] Event Scheduler: Loaded 0 events
2016-01-21T16:02:11.567640Z 0 [Note] /usr/local/Cellar/mysql/5.7.10/bin/mysqld: ready for connections.
Version: '5.7.10'  socket: '/tmp/mysql.sock'  port: 3306  Homebrew
2016-01-21T16:02:43.932237Z 0 [Note] InnoDB: Buffer pool(s) load completed at 160121 16:02:43
2016-01-21T16:44:56.300678Z 3 [Note] Aborted connection 3 to db: 'unconnected' user: 'root' host: 'localhost' (Got an error reading communication packets)
@craigcarnell

This comment has been minimized.

Copy link
Author

@craigcarnell craigcarnell commented Jan 21, 2016

and

160109 09:18:33 mysqld_safe Starting mysqld daemon with databases from /usr/local/var/mysql
2016-01-09T09:18:35.132605Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
2016-01-09T09:18:35.138757Z 0 [Warning] 'NO_ZERO_DATE', 'NO_ZERO_IN_DATE' and 'ERROR_FOR_DIVISION_BY_ZERO' sql modes should be used with strict mode. They will be merged with strict mode in a future release.
2016-01-09T09:18:35.138769Z 0 [Warning] 'NO_AUTO_CREATE_USER' sql mode was not set.
2016-01-09T09:18:35.139509Z 0 [Warning] Insecure configuration for --secure-file-priv: Current value does not restrict location of generated files. Consider setting it to a valid, non-empty path.
2016-01-09T09:18:35.139807Z 0 [Note] /usr/local/Cellar/mysql/5.7.10/bin/mysqld (mysqld 5.7.10) starting as process 468 ...
2016-01-09T09:18:35.147136Z 0 [Warning] Setting lower_case_table_names=2 because file system for /usr/local/var/mysql/ is case insensitive
2016-01-09T09:18:35.150133Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2016-01-09T09:18:35.150159Z 0 [Note] InnoDB: Uses event mutexes
2016-01-09T09:18:35.150167Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2016-01-09T09:18:35.150176Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.3
2016-01-09T09:18:35.150654Z 0 [Note] InnoDB: Number of pools: 1
2016-01-09T09:18:35.151046Z 0 [Note] InnoDB: Using CPU crc32 instructions
2016-01-09T09:18:35.366882Z 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
2016-01-09T09:18:35.685272Z 0 [Note] InnoDB: Completed initialization of buffer pool
2016-01-09T09:18:35.730033Z 0 [Note] InnoDB: Highest supported file format is Barracuda.
2016-01-09T09:18:35.734679Z 0 [Note] InnoDB: Log scan progressed past the checkpoint lsn 19004324316
2016-01-09T09:18:35.734700Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 19004324325
2016-01-09T09:18:35.734897Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 19004324325
2016-01-09T09:18:35.734912Z 0 [Note] InnoDB: Database was not shutdown normally!
2016-01-09T09:18:35.734919Z 0 [Note] InnoDB: Starting crash recovery.
2016-01-09T09:18:56.482676Z 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
2016-01-09T09:18:56.482698Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables
2016-01-09T09:18:56.482774Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2016-01-09T09:18:56.500456Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
2016-01-09T09:18:56.501357Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active.
2016-01-09T09:18:56.501371Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active.
2016-01-09T09:18:56.501549Z 0 [Note] InnoDB: Waiting for purge to start
2016-01-09T09:18:56.551909Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 20853ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-09T09:18:56.551918Z 0 [Note] InnoDB: 5.7.10 started; log sequence number 19004324325
2016-01-09T09:18:56.552163Z 0 [Note] InnoDB: not started
2016-01-09T09:18:56.552195Z 0 [Note] InnoDB: Loading buffer pool(s) from /usr/local/var/mysql/ib_buffer_pool
2016-01-09T09:18:56.552985Z 0 [Note] Plugin 'FEDERATED' is disabled.
2016-01-09T09:18:56.564574Z 0 [Note] Found ca.pem, server-cert.pem and server-key.pem in data directory. Trying to enable SSL support using them.
2016-01-09T09:18:56.564592Z 0 [Note] Skipping generation of SSL certificates as certificate files are present in data directory.
2016-01-09T09:18:56.570165Z 0 [Warning] CA certificate ca.pem is self signed.
2016-01-09T09:18:56.570235Z 0 [Note] Skipping generation of RSA key pair as key files are present in data directory.
2016-01-09T09:18:56.579012Z 0 [Note] Server hostname (bind-address): '127.0.0.1'; port: 3306
2016-01-09T09:18:56.579584Z 0 [Note]   - '127.0.0.1' resolves to '127.0.0.1';
2016-01-09T09:18:56.580009Z 0 [Note] Server socket created on IP: '127.0.0.1'.
2016-01-09T09:18:58.019415Z 0 [Note] InnoDB: Buffer pool(s) load completed at 160109  9:18:58
2016-01-09T09:18:58.877324Z 0 [Note] Event Scheduler: Loaded 0 events
2016-01-09T09:18:58.877455Z 0 [Note] /usr/local/Cellar/mysql/5.7.10/bin/mysqld: ready for connections.
Version: '5.7.10'  socket: '/tmp/mysql.sock'  port: 3306  Homebrew
2016-01-09T10:08:44.007126Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 735232ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-09T11:47:09.008143Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 5486040ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-09T14:06:55.007702Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 4320536ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-09T16:57:17.010360Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 9105994ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-09T19:06:32.007121Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 53165ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-09T20:54:09.009737Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 6422854ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-09T21:07:16.007319Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 13128ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-09T21:21:30.006589Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 13543ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-09T21:32:28.148720Z 0 [Note] Giving 0 client threads a chance to die gracefully
2016-01-09T21:32:28.148769Z 0 [Note] Shutting down slave threads
2016-01-09T21:32:28.150153Z 0 [Note] Forcefully disconnecting 0 remaining clients
2016-01-09T21:32:28.150201Z 0 [Note] Event Scheduler: Purging the queue. 0 events
2016-01-09T21:32:28.150303Z 0 [Note] Binlog end
2016-01-09T21:32:28.151560Z 0 [Note] Shutting down plugin 'ngram'
2016-01-09T21:32:28.151583Z 0 [Note] Shutting down plugin 'partition'
2016-01-09T21:32:28.151595Z 0 [Note] Shutting down plugin 'BLACKHOLE'
2016-01-09T21:32:28.151607Z 0 [Note] Shutting down plugin 'ARCHIVE'
2016-01-09T21:32:28.151617Z 0 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
2016-01-09T21:32:28.151677Z 0 [Note] Shutting down plugin 'MRG_MYISAM'
2016-01-09T21:32:28.151697Z 0 [Note] Shutting down plugin 'MyISAM'
2016-01-09T21:32:28.151737Z 0 [Note] Shutting down plugin 'INNODB_SYS_VIRTUAL'
2016-01-09T21:32:28.151749Z 0 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
2016-01-09T21:32:28.151759Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
2016-01-09T21:32:28.151769Z 0 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
2016-01-09T21:32:28.151778Z 0 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
2016-01-09T21:32:28.151788Z 0 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
2016-01-09T21:32:28.151804Z 0 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
2016-01-09T21:32:28.151820Z 0 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
2016-01-09T21:32:28.151833Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
2016-01-09T21:32:28.151843Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
2016-01-09T21:32:28.151853Z 0 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2016-01-09T21:32:28.151863Z 0 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2016-01-09T21:32:28.151873Z 0 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2016-01-09T21:32:28.151882Z 0 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2016-01-09T21:32:28.151892Z 0 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2016-01-09T21:32:28.151902Z 0 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2016-01-09T21:32:28.151912Z 0 [Note] Shutting down plugin 'INNODB_METRICS'
2016-01-09T21:32:28.151921Z 0 [Note] Shutting down plugin 'INNODB_TEMP_TABLE_INFO'
2016-01-09T21:32:28.151952Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2016-01-09T21:32:28.151963Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2016-01-09T21:32:28.151972Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2016-01-09T21:32:28.151982Z 0 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2016-01-09T21:32:28.151992Z 0 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2016-01-09T21:32:28.152002Z 0 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2016-01-09T21:32:28.152012Z 0 [Note] Shutting down plugin 'INNODB_CMPMEM'
2016-01-09T21:32:28.152021Z 0 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2016-01-09T21:32:28.152031Z 0 [Note] Shutting down plugin 'INNODB_CMP'
2016-01-09T21:32:28.152041Z 0 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
2016-01-09T21:32:28.152051Z 0 [Note] Shutting down plugin 'INNODB_LOCKS'
2016-01-09T21:32:28.152060Z 0 [Note] Shutting down plugin 'INNODB_TRX'
2016-01-09T21:32:28.152070Z 0 [Note] Shutting down plugin 'InnoDB'
2016-01-09T21:32:28.152172Z 0 [Note] InnoDB: FTS optimize thread exiting.
2016-01-09T21:32:28.152262Z 0 [Note] InnoDB: Starting shutdown...
160120 09:22:30 mysqld_safe Starting mysqld daemon with databases from /usr/local/var/mysql
2016-01-20T09:22:32.471810Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
2016-01-20T09:22:32.473749Z 0 [Warning] 'NO_ZERO_DATE', 'NO_ZERO_IN_DATE' and 'ERROR_FOR_DIVISION_BY_ZERO' sql modes should be used with strict mode. They will be merged with strict mode in a future release.
2016-01-20T09:22:32.473773Z 0 [Warning] 'NO_AUTO_CREATE_USER' sql mode was not set.
2016-01-20T09:22:32.473891Z 0 [Warning] Insecure configuration for --secure-file-priv: Current value does not restrict location of generated files. Consider setting it to a valid, non-empty path.
2016-01-20T09:22:32.474286Z 0 [Note] /usr/local/Cellar/mysql/5.7.10/bin/mysqld (mysqld 5.7.10) starting as process 468 ...
2016-01-20T09:22:32.483003Z 0 [Warning] Setting lower_case_table_names=2 because file system for /usr/local/var/mysql/ is case insensitive
2016-01-20T09:22:32.486177Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2016-01-20T09:22:32.486205Z 0 [Note] InnoDB: Uses event mutexes
2016-01-20T09:22:32.486215Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2016-01-20T09:22:32.486225Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.3
2016-01-20T09:22:32.486731Z 0 [Note] InnoDB: Number of pools: 1
2016-01-20T09:22:32.487161Z 0 [Note] InnoDB: Using CPU crc32 instructions
2016-01-20T09:22:32.519242Z 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
2016-01-20T09:22:32.824980Z 0 [Note] InnoDB: Completed initialization of buffer pool
2016-01-20T09:22:32.844154Z 0 [Note] InnoDB: Highest supported file format is Barracuda.
2016-01-20T09:22:32.855344Z 0 [Note] InnoDB: Log scan progressed past the checkpoint lsn 19711672536
2016-01-20T09:22:32.855372Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 19711672545
2016-01-20T09:22:32.855550Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 19711672545
2016-01-20T09:22:32.855563Z 0 [Note] InnoDB: Database was not shutdown normally!
2016-01-20T09:22:32.855569Z 0 [Note] InnoDB: Starting crash recovery.
2016-01-20T09:22:35.872541Z 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
2016-01-20T09:22:35.872571Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables
2016-01-20T09:22:35.872673Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2016-01-20T09:22:35.945964Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
2016-01-20T09:22:35.947646Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active.
2016-01-20T09:22:35.947672Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active.
2016-01-20T09:22:35.948005Z 0 [Note] InnoDB: Waiting for purge to start
2016-01-20T09:22:36.001824Z 0 [Note] InnoDB: 5.7.10 started; log sequence number 19711672545
2016-01-20T09:22:36.001953Z 0 [Note] InnoDB: not started
2016-01-20T09:22:36.001968Z 0 [Note] InnoDB: Loading buffer pool(s) from /usr/local/var/mysql/ib_buffer_pool
2016-01-20T09:22:36.002413Z 0 [Note] Plugin 'FEDERATED' is disabled.
2016-01-20T09:22:36.009370Z 0 [Note] Found ca.pem, server-cert.pem and server-key.pem in data directory. Trying to enable SSL support using them.
2016-01-20T09:22:36.009392Z 0 [Note] Skipping generation of SSL certificates as certificate files are present in data directory.
2016-01-20T09:22:36.017107Z 0 [Warning] CA certificate ca.pem is self signed.
2016-01-20T09:22:36.017205Z 0 [Note] Skipping generation of RSA key pair as key files are present in data directory.
2016-01-20T09:22:36.028684Z 0 [Note] Server hostname (bind-address): '127.0.0.1'; port: 3306
2016-01-20T09:22:36.029451Z 0 [Note]   - '127.0.0.1' resolves to '127.0.0.1';
2016-01-20T09:22:36.029492Z 0 [Note] Server socket created on IP: '127.0.0.1'.
2016-01-20T09:22:36.047112Z 0 [Note] InnoDB: Buffer pool(s) load completed at 160120  9:22:36
2016-01-20T09:22:36.114453Z 0 [Note] Event Scheduler: Loaded 0 events
2016-01-20T09:22:36.114593Z 0 [Note] /usr/local/Cellar/mysql/5.7.10/bin/mysqld: ready for connections.
Version: '5.7.10'  socket: '/tmp/mysql.sock'  port: 3306  Homebrew
2016-01-20T10:13:28.007481Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 57333ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-20T11:18:46.009076Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 3632272ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-20T11:32:25.583306Z 0 [Note] Giving 0 client threads a chance to die gracefully
2016-01-20T11:32:25.583331Z 0 [Note] Shutting down slave threads
2016-01-20T11:32:25.584488Z 0 [Note] Forcefully disconnecting 0 remaining clients
2016-01-20T11:32:25.584512Z 0 [Note] Event Scheduler: Purging the queue. 0 events
2016-01-20T11:32:25.584562Z 0 [Note] Binlog end
2016-01-20T11:32:25.585225Z 0 [Note] Shutting down plugin 'ngram'
2016-01-20T11:32:25.585236Z 0 [Note] Shutting down plugin 'partition'
2016-01-20T11:32:25.585242Z 0 [Note] Shutting down plugin 'BLACKHOLE'
2016-01-20T11:32:25.585248Z 0 [Note] Shutting down plugin 'ARCHIVE'
2016-01-20T11:32:25.585253Z 0 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
2016-01-20T11:32:25.585282Z 0 [Note] Shutting down plugin 'MRG_MYISAM'
2016-01-20T11:32:25.585291Z 0 [Note] Shutting down plugin 'MyISAM'
2016-01-20T11:32:25.585310Z 0 [Note] Shutting down plugin 'INNODB_SYS_VIRTUAL'
2016-01-20T11:32:25.585316Z 0 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
2016-01-20T11:32:25.585320Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
2016-01-20T11:32:25.585325Z 0 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
2016-01-20T11:32:25.585330Z 0 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
2016-01-20T11:32:25.585334Z 0 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
2016-01-20T11:32:25.585339Z 0 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
2016-01-20T11:32:25.585343Z 0 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
2016-01-20T11:32:25.585348Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
2016-01-20T11:32:25.585352Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
2016-01-20T11:32:25.585357Z 0 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
2016-01-20T11:32:25.585361Z 0 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
2016-01-20T11:32:25.585366Z 0 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
2016-01-20T11:32:25.585370Z 0 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
2016-01-20T11:32:25.585375Z 0 [Note] Shutting down plugin 'INNODB_FT_DELETED'
2016-01-20T11:32:25.585380Z 0 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
2016-01-20T11:32:25.585384Z 0 [Note] Shutting down plugin 'INNODB_METRICS'
2016-01-20T11:32:25.585389Z 0 [Note] Shutting down plugin 'INNODB_TEMP_TABLE_INFO'
2016-01-20T11:32:25.585394Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
2016-01-20T11:32:25.585408Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
2016-01-20T11:32:25.585413Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
2016-01-20T11:32:25.585418Z 0 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
2016-01-20T11:32:25.585422Z 0 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
2016-01-20T11:32:25.585427Z 0 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
2016-01-20T11:32:25.585432Z 0 [Note] Shutting down plugin 'INNODB_CMPMEM'
2016-01-20T11:32:25.585436Z 0 [Note] Shutting down plugin 'INNODB_CMP_RESET'
2016-01-20T11:32:25.585441Z 0 [Note] Shutting down plugin 'INNODB_CMP'
2016-01-20T11:32:25.585445Z 0 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
2016-01-20T11:32:25.585450Z 0 [Note] Shutting down plugin 'INNODB_LOCKS'
2016-01-20T11:32:25.585454Z 0 [Note] Shutting down plugin 'INNODB_TRX'
2016-01-20T11:32:25.585459Z 0 [Note] Shutting down plugin 'InnoDB'
2016-01-20T11:32:25.585511Z 0 [Note] InnoDB: FTS optimize thread exiting.
2016-01-20T11:32:25.585555Z 0 [Note] InnoDB: Starting shutdown...
@craigcarnell

This comment has been minimized.

Copy link
Author

@craigcarnell craigcarnell commented Jan 21, 2016

launchctl limit maxfiles

    maxfiles    256            unlimited  

ulimit -a

core file size          (blocks, -c) 0
data seg size           (kbytes, -d) unlimited
file size               (blocks, -f) unlimited
max locked memory       (kbytes, -l) unlimited
max memory size         (kbytes, -m) unlimited
open files                      (-n) 524288
pipe size            (512 bytes, -p) 1
stack size              (kbytes, -s) 8192
cpu time               (seconds, -t) unlimited
max user processes              (-u) 709

I am going to try:
/Library/LaunchDaemons/limit.maxfiles.plist

<?xml version="1.0" encoding="UTF-8"?>
<!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN"
        "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
<plist version="1.0">
  <dict>
    <key>Label</key>
    <string>limit.maxfiles</string>
    <key>ProgramArguments</key>
    <array>
      <string>launchctl</string>
      <string>limit</string>
      <string>maxfiles</string>
      <string>524288</string>
      <string>524288</string>
    </array>
    <key>RunAtLoad</key>
    <true/>
    <key>ServiceIPC</key>
    <false/>
  </dict>
</plist>

After a reboot:

launchctl limit maxfiles 
    maxfiles    524288         524288 

But it still crashed:

160121 17:01:38 mysqld_safe Starting mysqld daemon with databases from /usr/local/var/mysql
2016-01-21T17:01:40.086587Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
2016-01-21T17:01:40.086998Z 0 [Warning] 'NO_ZERO_DATE', 'NO_ZERO_IN_DATE' and 'ERROR_FOR_DIVISION_BY_ZERO' sql modes should be used with strict mode. They will be merged with strict mode in a future release.
2016-01-21T17:01:40.087010Z 0 [Warning] 'NO_AUTO_CREATE_USER' sql mode was not set.
2016-01-21T17:01:40.087099Z 0 [Warning] Insecure configuration for --secure-file-priv: Current value does not restrict location of generated files. Consider setting it to a valid, non-empty path.
2016-01-21T17:01:40.087292Z 0 [Note] /usr/local/Cellar/mysql/5.7.10/bin/mysqld (mysqld 5.7.10) starting as process 502 ...
2016-01-21T17:01:40.093539Z 0 [Warning] Setting lower_case_table_names=2 because file system for /usr/local/var/mysql/ is case insensitive
2016-01-21T17:01:40.096970Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2016-01-21T17:01:40.096999Z 0 [Note] InnoDB: Uses event mutexes
2016-01-21T17:01:40.097007Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2016-01-21T17:01:40.097016Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.3
2016-01-21T17:01:40.097653Z 0 [Note] InnoDB: Number of pools: 1
2016-01-21T17:01:40.098127Z 0 [Note] InnoDB: Using CPU crc32 instructions
2016-01-21T17:01:40.113588Z 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
2016-01-21T17:01:40.456965Z 0 [Note] InnoDB: Completed initialization of buffer pool
2016-01-21T17:01:40.484313Z 0 [Note] InnoDB: Highest supported file format is Barracuda.
2016-01-21T17:01:40.485137Z 0 [Note] InnoDB: Log scan progressed past the checkpoint lsn 19971126341
2016-01-21T17:01:40.485159Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 19971126350
2016-01-21T17:01:40.485315Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 19971126350
2016-01-21T17:01:40.485326Z 0 [Note] InnoDB: Database was not shutdown normally!
2016-01-21T17:01:40.485332Z 0 [Note] InnoDB: Starting crash recovery.
2016-01-21T17:01:44.409693Z 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
2016-01-21T17:01:44.409727Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables
2016-01-21T17:01:44.409868Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2016-01-21T17:01:44.432983Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
2016-01-21T17:01:44.434348Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active.
2016-01-21T17:01:44.434371Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active.
2016-01-21T17:01:44.434700Z 0 [Note] InnoDB: Waiting for purge to start
2016-01-21T17:01:44.488071Z 0 [Note] InnoDB: page_cleaner: 1000ms intended loop took 4029ms. The settings might not be optimal. (flushed=0 and evicted=0, during the time.)
2016-01-21T17:01:44.488166Z 0 [Note] InnoDB: 5.7.10 started; log sequence number 19971126350
2016-01-21T17:01:44.488703Z 0 [Note] InnoDB: Loading buffer pool(s) from /usr/local/var/mysql/ib_buffer_pool
2016-01-21T17:01:44.488727Z 0 [Note] InnoDB: not started
2016-01-21T17:01:44.541293Z 0 [Note] Plugin 'FEDERATED' is disabled.
2016-01-21T17:01:45.487288Z 0 [Note] Found ca.pem, server-cert.pem and server-key.pem in data directory. Trying to enable SSL support using them.
2016-01-21T17:01:45.487314Z 0 [Note] Skipping generation of SSL certificates as certificate files are present in data directory.
2016-01-21T17:01:45.572819Z 0 [Warning] CA certificate ca.pem is self signed.
2016-01-21T17:01:45.572912Z 0 [Note] Skipping generation of RSA key pair as key files are present in data directory.
2016-01-21T17:01:45.684929Z 0 [Note] Server hostname (bind-address): '127.0.0.1'; port: 3306
2016-01-21T17:01:45.685662Z 0 [Note]   - '127.0.0.1' resolves to '127.0.0.1';
2016-01-21T17:01:45.685897Z 0 [Note] Server socket created on IP: '127.0.0.1'.
2016-01-21T17:01:46.196251Z 0 [Note] Event Scheduler: Loaded 0 events
2016-01-21T17:01:46.196403Z 0 [Note] /usr/local/Cellar/mysql/5.7.10/bin/mysqld: ready for connections.
Version: '5.7.10'  socket: '/tmp/mysql.sock'  port: 3306  Homebrew
2016-01-21T17:01:48.656709Z 0 [Note] InnoDB: Buffer pool(s) load completed at 160121 17:01:48
2016-01-21T17:09:34.802115Z 0 [Warning] File Descriptor 1539 exceedeed FD_SETSIZE=1024
2016-01-21T17:09:37.778012Z 0 [Warning] File Descriptor 1539 exceedeed FD_SETSIZE=1024

launchctl limit

    cpu         unlimited      unlimited      
    filesize    unlimited      unlimited      
    data        unlimited      unlimited      
    stack       8388608        67104768       
    core        0              unlimited      
    rss         unlimited      unlimited      
    memlock     unlimited      unlimited      
    maxproc     709            1064           
    maxfiles    524288         524288         
Craigs-MacBook-Pro:mysql ccarnell$ sudo launchctl limit
    cpu         unlimited      unlimited      
    filesize    unlimited      unlimited      
    data        unlimited      unlimited      
    stack       8388608        67104768       
    core        0              unlimited      
    rss         unlimited      unlimited      
    memlock     unlimited      unlimited      
    maxproc     709            1064           
    maxfiles    524288         524288  
@MikeMcQuaid

This comment has been minimized.

Copy link
Member

@MikeMcQuaid MikeMcQuaid commented Jan 25, 2016

@craigcarnell Can you try using a MySQL from outside Homebrew with the same version and let us know if it has the same issues? I suspect this is a MySQL rather than Homebrew bug.

@dunn dunn added the needs response label Jan 28, 2016
@craigfrancis

This comment has been minimized.

Copy link

@craigfrancis craigfrancis commented Mar 18, 2016

I think this is a MySQL problem.

I've been having exactly the same issue, but fixed it by adding table_open_cache=250 to my ~/.my.cnf file.

It was crashing every 2-3 days, but has been fine for about 4 weeks now.


Previously I've tried adding <string>--open-files-limit=8192</string> to:

https://github.com/Homebrew/homebrew/blob/master/Library/Formula/mysql.rb#L173

As adding this command line flag, the ./mysqld_safe script will call ulimit for you (on line 610), then start MySQL so that it also works with the open-files-limit of 8192... but that did not work.


Two other suggestions I've seen (but did not try) were:

innodb_file_per_table = OFF
table_definition_cache = 400
@HenningCash

This comment has been minimized.

Copy link

@HenningCash HenningCash commented Apr 4, 2016

I had the same issue when installing TYPO3 7.6 and adding table_open_cache = 250 fixed it for me. I did not even have to increase max_allowed_packet. Thank you @craigfrancis!

@Homebrew Homebrew locked and limited conversation to collaborators Jul 10, 2016
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Projects
None yet
7 participants
You can’t perform that action at this time.