Description:
I use homebrew to install and update mysql since 8.0.30.
After upgrading from 9.2.0 to 9.3.0, I started getting the error when running the mysql command:
ERROR 2002 (HY000): Can't connect to local MySQL server through socket '/tmp/mysql.sock' (2)
After researching that issue and trying to figure out why the '/tmp/mysql.sock' was missing, I finally looked at the Activity Monitor and saw that mysqld was running at or close to 100% CPU.
I tried using 'brew services stop mysql' to stop mysqld, but it didn't stop. I finally had to use a 'kill -9' to stop it.
I reinstalled mysql using 'brew uninstall mysql' and 'brew install mysql'. With the same results.
I updated the command used to start mysqld with the log-error-verbosity option set to 3. Running the command:
/usr/local/opt/mysql/bin/mysqld --basedir=/usr/local/opt/mysql --datadir=/usr/local/var/mysql --plugin-dir=/usr/local/opt/mysql/lib/plugin --log-error=Marks-MacBook-Pro.local.err --pid-file=Marks-MacBook-Pro.local.pid --log-error-verbosity=3
I got the following log entries:
Log entries when starting mysqld with log_error_verbosity=3
===========================================================
2025-05-18T22:44:13.166693Z 0 [System] [MY-015015] [Server] MySQL Server - start.
2025-05-18T22:44:13.399374Z 0 [Note] [MY-010098] [Server] --secure-file-priv is set to NULL. Operations related to importing and exporting data are disabled
2025-05-18T22:44:13.399415Z 0 [Note] [MY-010949] [Server] Basedir set to /usr/local/Cellar/mysql/9.3.0/.
2025-05-18T22:44:13.399423Z 0 [System] [MY-010116] [Server] /usr/local/opt/mysql/bin/mysqld (mysqld 9.3.0) starting as process 86081
2025-05-18T22:44:13.402766Z 0 [Warning] [MY-010159] [Server] Setting lower_case_table_names=2 because file system for /usr/local/var/mysql/ is case insensitive
2025-05-18T22:44:13.431382Z 0 [Note] [MY-015140] [InnoDB] Using innodb_flush_method=fsync
2025-05-18T22:44:13.431611Z 0 [Note] [MY-010747] [Server] Plugin 'FEDERATED' is disabled.
2025-05-18T22:44:13.431682Z 0 [Note] [MY-010747] [Server] Plugin 'ndbcluster' is disabled.
2025-05-18T22:44:13.431704Z 0 [Note] [MY-010747] [Server] Plugin 'ndbinfo' is disabled.
2025-05-18T22:44:13.431717Z 0 [Note] [MY-010747] [Server] Plugin 'ndb_transid_mysql_connection_map' is disabled.
2025-05-18T22:44:13.432991Z 1 [System] [MY-013576] [InnoDB] InnoDB initialization has started.
2025-05-18T22:44:13.433017Z 1 [Note] [MY-013546] [InnoDB] Atomic write enabled
2025-05-18T22:44:13.433081Z 1 [Note] [MY-012944] [InnoDB] Uses event mutexes
2025-05-18T22:44:13.433101Z 1 [Note] [MY-012945] [InnoDB] GCC builtin __atomic_thread_fence() is used for memory barrier
2025-05-18T22:44:13.433113Z 1 [Note] [MY-012948] [InnoDB] Compressed tables use zlib 1.3.1
2025-05-18T22:44:13.436381Z 1 [Note] [MY-012951] [InnoDB] Using hardware accelerated crc32 and polynomial multiplication.
2025-05-18T22:44:13.436831Z 1 [Note] [MY-012203] [InnoDB] Directories to scan './'
2025-05-18T22:44:13.436897Z 1 [Note] [MY-012204] [InnoDB] Scanning './'
2025-05-18T22:44:13.439164Z 1 [Note] [MY-012208] [InnoDB] Completed space ID check of 15 files.
2025-05-18T22:44:13.439705Z 1 [Note] [MY-012955] [InnoDB] Initializing buffer pool, total size = 128.000000M, instances = 1, chunk size =128.000000M
2025-05-18T22:44:13.463335Z 1 [Note] [MY-012957] [InnoDB] Completed initialization of buffer pool
2025-05-18T22:44:13.466573Z 1 [Note] [MY-013532] [InnoDB] Using './#ib_16384_0.dblwr' for doublewrite
2025-05-18T22:44:13.475594Z 1 [Note] [MY-013532] [InnoDB] Using './#ib_16384_1.dblwr' for doublewrite
2025-05-18T22:44:13.497704Z 1 [Note] [MY-013566] [InnoDB] Double write buffer files: 2
2025-05-18T22:44:13.497743Z 1 [Note] [MY-013565] [InnoDB] Double write buffer pages per instance: 128
2025-05-18T22:44:13.497816Z 1 [Note] [MY-013532] [InnoDB] Using './#ib_16384_0.dblwr' for doublewrite
2025-05-18T22:44:13.497880Z 1 [Note] [MY-013532] [InnoDB] Using './#ib_16384_1.dblwr' for doublewrite
2025-05-18T22:44:13.539267Z 1 [Note] [MY-013883] [InnoDB] The latest found checkpoint is at lsn = 1778285177 in redo log file ./#innodb_redo/#ib_redo543.
2025-05-18T22:44:13.539369Z 1 [Note] [MY-012560] [InnoDB] The log sequence number 1778279708 in the system tablespace does not match the log sequence number 1778285177 in the redo log files!
2025-05-18T22:44:13.539391Z 1 [Note] [MY-012551] [InnoDB] Database was not shutdown normally!
2025-05-18T22:44:13.539404Z 1 [Note] [MY-012552] [InnoDB] Starting crash recovery.
2025-05-18T22:44:13.545159Z 1 [Note] [MY-013086] [InnoDB] Starting to parse redo log at lsn = 1778285177, whereas checkpoint_lsn = 1778285177 and start_lsn = 1778285056
2025-05-18T22:44:13.545213Z 1 [Note] [MY-012550] [InnoDB] Doing recovery: scanned up to log sequence number 1778285177
2025-05-18T22:44:13.545226Z 1 [Note] [MY-012532] [InnoDB] Applying a batch of 0 redo log records ...
2025-05-18T22:44:13.545278Z 1 [Note] [MY-012535] [InnoDB] Apply batch completed!
2025-05-18T22:44:13.650844Z 1 [Note] [MY-013083] [InnoDB] Log background threads are being started...
2025-05-18T22:44:13.654910Z 1 [Note] [MY-013252] [InnoDB] Using undo tablespace './undo_001'.
2025-05-18T22:44:13.656044Z 1 [Note] [MY-013252] [InnoDB] Using undo tablespace './undo_002'.
2025-05-18T22:44:13.658420Z 1 [Note] [MY-012910] [InnoDB] Opened 2 existing undo tablespaces.
2025-05-18T22:44:13.658566Z 1 [Note] [MY-011980] [InnoDB] GTID recovery trx_no: 662356
2025-05-18T22:44:13.701494Z 1 [Note] [MY-013776] [InnoDB] Parallel initialization of rseg complete
2025-05-18T22:44:13.701541Z 1 [Note] [MY-013777] [InnoDB] Time taken to initialize rseg using 4 thread: 42 ms.
2025-05-18T22:44:13.702017Z 1 [Note] [MY-012255] [InnoDB] Removed temporary tablespace data file: "ibtmp1"
2025-05-18T22:44:13.702041Z 1 [Note] [MY-012923] [InnoDB] Creating shared tablespace for temporary tables
2025-05-18T22:44:13.702262Z 1 [Note] [MY-012265] [InnoDB] Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2025-05-18T22:44:13.707885Z 1 [Note] [MY-012266] [InnoDB] File './ibtmp1' size is now 12 MB.
2025-05-18T22:44:13.708071Z 1 [Note] [MY-013627] [InnoDB] Scanning temp tablespace dir:'./#innodb_temp/'
2025-05-18T22:44:13.714555Z 1 [Note] [MY-013018] [InnoDB] Created 128 and tracked 128 new rollback segment(s) in the temporary tablespace. 128 are now active.
2025-05-18T22:44:13.714741Z 1 [Note] [MY-012976] [InnoDB] 9.3.0 started; log sequence number 1778285187
2025-05-18T22:44:13.715323Z 1 [System] [MY-013577] [InnoDB] InnoDB initialization has ended.
2025-05-18T22:44:13.722258Z 1 [Note] [MY-013327] [Server] MySQL server upgrading from version '90200' to '90300'.
2025-05-18T22:44:13.843981Z 1 [Note] [MY-012357] [InnoDB] Reading DD tablespace files
2025-05-18T22:44:13.856215Z 1 [Note] [MY-012356] [InnoDB] Scanned 17 tablespaces. Validated 17.
This is where it hangs until I do a kill -9 to stop it.
How to repeat:
Don't know how to repeat.
