Re: mysqld restarts with no apparent reason - Heikki Tuuri - com.mysql.lists.mysql

5 messages in com.mysql.lists.mysqlRe: mysqld restarts with no apparent ...

From	Sent On	Attachments
Alfredo Reynoso	26 Jan 2005 09:35
Gleb Paharenko	26 Jan 2005 10:56
Alfredo Reynoso	27 Jan 2005 08:51
Gleb Paharenko	28 Jan 2005 01:55
Heikki Tuuri	30 Jan 2005 09:03

Subject:	Re: mysqld restarts with no apparent reason
From:	Heikki Tuuri (Heik...@innodb.com)
Date:	01/30/2005 09:03:26 AM
List:	com.mysql.lists.mysql

All,

the InnoDB prints below reveal nothing of the problem. They just show mysqld crashing and starting uo again.

You are not using InnoDB tables, since the log sequence number does not grow.

Best regards,

----- Original Message ----- From: ""Alfredo Reynoso"" <sil...@hotmail.com> Newsgroups: mailing.database.myodbc Sent: Thursday, January 27, 2005 6:57 PM Subject: Re: mysqld restarts with no apparent reason

This is the error log (comes from the file hostname.com.err in the data directory). The part where mysqld is restarted comes at the end, I've cutted most of the restarts because the only diference is the hour, and because there were a lot of restarts.

One more thing, I think this mail would begin a new thread because its a hand made reply. If that is the case Im really sorry, this is the first time I ever post in a mailing list, and I was expecting the answer in my mail, but I didnt got it that way. If no new thread is created void this comment =).

050122 19:01:56 mysqld started InnoDB: The first specified data file ./ibdata1 did not exist: InnoDB: a new database to be created! 050122 19:01:57 InnoDB: Setting file ./ibdata1 size to 10 MB InnoDB: Database physically writes the file full: wait... 050122 19:02:15 InnoDB: Log file ./ib_logfile0 did not exist: new to be created InnoDB: Setting log file ./ib_logfile0 size to 5 MB InnoDB: Database physically writes the file full: wait... 050122 19:02:17 InnoDB: Log file ./ib_logfile1 did not exist: new to be created InnoDB: Setting log file ./ib_logfile1 size to 5 MB InnoDB: Database physically writes the file full: wait... InnoDB: Doublewrite buffer not found: creating new InnoDB: Doublewrite buffer created InnoDB: Creating foreign key constraint system tables InnoDB: Foreign key constraint system tables created 050122 19:02:23 InnoDB: Started /usr/local/mysql/libexec/mysqld: ready for connections. Version: '4.0.23' socket: '/tmp/mysql.sock' port: 3306 Source distribution 050122 19:02:23 Error in accept: Invalid argument 050125 11:02:18 mysqld started 050125 11:02:21 InnoDB: Database was not shut down normally. InnoDB: Starting recovery from log files... InnoDB: Starting log scan based on checkpoint at InnoDB: log sequence number 0 37082 InnoDB: Doing recovery: scanned up to log sequence number 0 43892 050125 11:02:22 InnoDB: Starting an apply batch of log records to the database... InnoDB: Progress in percents: 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 32 33 34 35 36 37 38 39 40 41 42 43 44 45 46 47 48 49 50 51 52 53 54 55 56 57 58 59 60 61 62 63 64 65 66 67 68 69 70 71 72 73 74 75 76 77 78 79 80 81 82 83 84 85 86 87 88 89 90 91 92 93 94 95 96 97 98 99 InnoDB: Apply batch completed 050125 11:02:22 InnoDB: Flushing modified pages from the buffer pool... 050125 11:02:23 InnoDB: Started /usr/local/mysql/libexec/mysqld: ready for connections. Version: '4.0.23' socket: '/tmp/mysql.sock' port: 3306 Source distribution 050125 11:32:52 mysqld started Fatal error: Can't change to run as user '=root'. Please check that the user exists! 050125 11:32:53 Aborting

050125 11:32:53 /usr/local/mysql/libexec/mysqld: Shutdown Complete

050125 11:32:53 mysqld ended

050125 11:33:24 mysqld started 050125 11:33:26 InnoDB: Database was not shut down normally. InnoDB: Starting recovery from log files... InnoDB: Starting log scan based on checkpoint at InnoDB: log sequence number 0 43902 InnoDB: Doing recovery: scanned up to log sequence number 0 43902 050125 11:33:27 InnoDB: Flushing modified pages from the buffer pool... 050125 11:33:27 InnoDB: Started /usr/local/mysql/libexec/mysqld: ready for connections. Version: '4.0.23' socket: '/tmp/mysql.sock' port: 3306 Source distribution 050125 11:57:00 mysqld restarted 050125 12:02:02 mysqld started 050125 12:02:05 InnoDB: Database was not shut down normally. InnoDB: Starting recovery from log files... InnoDB: Starting log scan based on checkpoint at InnoDB: log sequence number 0 43902 InnoDB: Doing recovery: scanned up to log sequence number 0 43902 050125 12:02:06 InnoDB: Flushing modified pages from the buffer pool... 050125 12:02:06 InnoDB: Started /usr/local/mysql/libexec/mysqld: ready for connections. Version: '4.0.23' socket: '/tmp/mysql.sock' port: 3306 Source distribution 050125 12:03:53 mysqld started 050125 12:03:56 InnoDB: Database was not shut down normally. InnoDB: Starting recovery from log files... InnoDB: Starting log scan based on checkpoint at InnoDB: log sequence number 0 43902 InnoDB: Doing recovery: scanned up to log sequence number 0 43902 050125 12:03:57 InnoDB: Flushing modified pages from the buffer pool... 050125 12:03:57 InnoDB: Started /usr/local/mysql/libexec/mysqld: ready for connections. Version: '4.0.23' socket: '/tmp/mysql.sock' port: 3306 Source distribution 050125 12:13:18 mysqld restarted 050125 12:13:19 InnoDB: Database was not shut down normally. InnoDB: Starting recovery from log files... InnoDB: Starting log scan based on checkpoint at InnoDB: log sequence number 0 43902 InnoDB: Doing recovery: scanned up to log sequence number 0 43902 050125 12:13:19 InnoDB: Flushing modified pages from the buffer pool... 050125 12:13:19 InnoDB: Started /usr/local/mysql/libexec/mysqld: ready for connections. Version: '4.0.23' socket: '/tmp/mysql.sock' port: 3306 Source distribution 050125 12:14:52 mysqld restarted 050125 12:14:53 InnoDB: Database was not shut down normally. InnoDB: Starting recovery from log files... InnoDB: Starting log scan based on checkpoint at InnoDB: log sequence number 0 43902 InnoDB: Doing recovery: scanned up to log sequence number 0 43902 050125 12:14:53 InnoDB: Flushing modified pages from the buffer pool... 050125 12:14:53 InnoDB: Started /usr/local/mysql/libexec/mysqld: ready for connections. Version: '4.0.23' socket: '/tmp/mysql.sock' port: 3306 Source distribution 050125 12:41:43 mysqld restarted 050125 12:41:45 InnoDB: Database was not shut down normally. InnoDB: Starting recovery from log files... InnoDB: Starting log scan based on checkpoint at InnoDB: log sequence number 0 43902 InnoDB: Doing recovery: scanned up to log sequence number 0 43902 050125 12:41:45 InnoDB: Flushing modified pages from the buffer pool... 050125 12:41:45 InnoDB: Started /usr/local/mysql/libexec/mysqld: ready for connections. Version: '4.0.23' socket: '/tmp/mysql.sock' port: 3306 Source distribution 050125 12:43:19 mysqld restarted 050125 12:43:19 InnoDB: Database was not shut down normally. InnoDB: Starting recovery from log files... InnoDB: Starting log scan based on checkpoint at InnoDB: log sequence number 0 43902 InnoDB: Doing recovery: scanned up to log sequence number 0 43902 050125 12:43:19 InnoDB: Flushing modified pages from the buffer pool... 050125 12:43:19 InnoDB: Started /usr/local/mysql/libexec/mysqld: ready for connections. Version: '4.0.23' socket: '/tmp/mysql.sock' port: 3306 Source distribution 050125 12:44:00 mysqld restarted 050125 12:44:00 InnoDB: Database was not shut down normally. InnoDB: Starting recovery from log files... InnoDB: Starting log scan based on checkpoint at InnoDB: log sequence number 0 43902 InnoDB: Doing recovery: scanned up to log sequence number 0 43902 050125 12:44:00 InnoDB: Flushing modified pages from the buffer pool... 050125 12:44:00 InnoDB: Started /usr/local/mysql/libexec/mysqld: ready for connections. Version: '4.0.23' socket: '/tmp/mysql.sock' port: 3306 Source distribution 050125 15:58:44 mysqld restarted 050125 15:58:45 InnoDB: Database was not shut down normally. InnoDB: Starting recovery from log files... InnoDB: Starting log scan based on checkpoint at InnoDB: log sequence number 0 43902 InnoDB: Doing recovery: scanned up to log sequence number 0 43902 050125 15:58:45 InnoDB: Flushing modified pages from the buffer pool... 050125 15:58:45 InnoDB: Started /usr/local/mysql/libexec/mysqld: ready for connections. Version: '4.0.23' socket: '/tmp/mysql.sock' port: 3306 Source distribution

Hello.

What's in the error log? Usually it can be found in the data directory, if another location wasn't specified in the config file or the command line options. MySQL server was restared by the mysqld_safe script, through the crash of mysqld process.

"Alfredo Reynoso" <siliat@stripped> wrote: Hi, Im running MySQL 4.0.23 (build from source with skunkware gcc 2.95.2pl1

and a precompiled FSU-pthreads 3.5c) under SCO Openserver 5.0.4.

It runs great by itself, the problem comes when I make a program using the C API, it restart mysqld the second (sometimes third) time I run my program, so my program hangs expecting the answer from the already finished mysqld.

Here is the example (a.out is my program):

# mysqld_safe --user=root --log & 448 # Starting mysqld daemon with databases from /usr/local/mysql/var

# ps -u root

448 tty01 00:00:00 mysqld_safe 468 tty01 00:00:01 mysqld

# a.out # ps -u root

448 tty01 00:00:00 mysqld_safe 468 tty01 00:00:01 mysqld

# a.out 050126 12:19:34 mysqld restarted //Here it hangs until I press Ctrl + Pause

# ps -u root

448 tty01 00:00:00 mysqld_safe 480 tty01 00:00:01 mysqld

The output in hostname.log is the following:

/usr/local/mysql/libexec/mysqld, Version: 4.0.23-log, started with: Tcp port: 3306 Unix socket: /tmp/mysql.sock Time Id Command Argument 050126 12:19:29 1 Connect root@localhost on 1 Quit /usr/local/mysql/libexec/mysqld, Version: 4.0.23-log, started with: Tcp port: 3306 Unix socket: /tmp/mysql.sock Time Id Command Argument

As you see, there is no message from the server that explains why does mysqld restarted.

I've already googled for it, but there is no such case in the internet material.

Can you help me?

Thanks.