[debian-mysql] Bug#548956: Bug#548956: mysql-server-5.1: mysql fails to start: segfault in ld-2.9.so

Sat Oct 17 21:16:36 UTC 2009

On 2009-10-17 Norbert Tretkowski wrote:
> Am Dienstag, den 29.09.2009, 22:05 +0200 schrieb Diederik de Haas:
> > After the latest upgrade to the latest mysql-server-5.1 package,
> > mysql-server fails to start.
> 
> Are you sure that this isn't a hardware issue?
> 
> You're the only one seeing this problem, hence I'm reducing it's
> severity.
> 
If it was a hardware issue, shouldn't I have the same issue with 5.1.37-2?

Anyway, trying to reproduce the issue, I did "aptitude unhold mysql-server-5.1" and then a safe-upgrade:

# aptitude safe-upgrade
...
The following packages will be upgraded:       
  mysql-server-5.1                             
The following packages are RECOMMENDED but will NOT be installed:
  libhtml-template-perl                                          
1 packages upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
Need to get 0B/11.0MB of archives. After unpacking 8,192B will be used.
Do you want to continue? [Y/n/?]
Writing extended state information... Done
Preconfiguring packages ...
(Reading database ... 86705 files and directories currently installed.)
Preparing to replace mysql-server-5.1 5.1.37-2 (using .../mysql-server-5.1_5.1.39-1_i386.deb) ...
Stopping MySQL database server: mysqld.
Stopping MySQL database server: mysqld.
Unpacking replacement mysql-server-5.1 ...
Processing triggers for man-db ...
Setting up mysql-server-5.1 (5.1.39-1) ...
Stopping MySQL database server: mysqld.
chown: cannot access `/var/run/mysqld': No such file or directory
insserv: warning: current stop runlevel(s) (1) of script `nvidia-kernel' overwrites defaults (empty).
insserv: warning: current stop runlevel(s) (0 1 6) of script `nvidia-glx' overwrites defaults (empty).
Starting MySQL database server: mysqld . . . . . . . . . . . . . . failed!
invoke-rc.d: initscript mysql, action "start" failed.
dpkg: error processing mysql-server-5.1 (--configure):
 subprocess installed post-installation script returned error exit status 1
Errors were encountered while processing:
 mysql-server-5.1
E: Sub-process /usr/bin/dpkg returned an error code (1)
A package failed to install.  Trying to recover:
Setting up mysql-server-5.1 (5.1.39-1) ...
Stopping MySQL database server: mysqld.
insserv: warning: current stop runlevel(s) (1) of script `nvidia-kernel' overwrites defaults (empty).
insserv: warning: current stop runlevel(s) (0 1 6) of script `nvidia-glx' overwrites defaults (empty).
Starting MySQL database server: mysqld . . . . . . . . . . . . . . failed!
invoke-rc.d: initscript mysql, action "start" failed.
dpkg: error processing mysql-server-5.1 (--configure):
 subprocess installed post-installation script returned error exit status 1
Errors were encountered while processing:
 mysql-server-5.1
Reading package lists... Done
Building dependency tree
Reading state information... Done
Reading extended state information
Initializing package states... Done
Reading task descriptions... Done

From /var/log I can see that mysql.log and mysql.err are both empty.
dpkg.log tells me:
2009-10-17 22:34:47 startup archives unpack
2009-10-17 22:34:47 upgrade mysql-server-5.1 5.1.37-2 5.1.39-1
2009-10-17 22:34:47 status half-configured mysql-server-5.1 5.1.37-2
2009-10-17 22:34:48 status unpacked mysql-server-5.1 5.1.37-2
2009-10-17 22:34:48 status half-installed mysql-server-5.1 5.1.37-2
2009-10-17 22:34:49 status triggers-pending man-db 2.5.6-3
2009-10-17 22:34:49 status half-installed mysql-server-5.1 5.1.37-2
2009-10-17 22:34:50 status half-installed mysql-server-5.1 5.1.37-2
2009-10-17 22:34:50 status unpacked mysql-server-5.1 5.1.39-1
2009-10-17 22:34:50 status unpacked mysql-server-5.1 5.1.39-1
2009-10-17 22:34:50 trigproc man-db 2.5.6-3 2.5.6-3
2009-10-17 22:34:50 status half-configured man-db 2.5.6-3
2009-10-17 22:34:50 status installed man-db 2.5.6-3
2009-10-17 22:34:51 startup packages configure
2009-10-17 22:34:51 configure mysql-server-5.1 5.1.39-1 5.1.39-1
2009-10-17 22:34:51 status unpacked mysql-server-5.1 5.1.39-1
2009-10-17 22:34:51 status unpacked mysql-server-5.1 5.1.39-1
2009-10-17 22:34:51 status unpacked mysql-server-5.1 5.1.39-1
2009-10-17 22:34:51 status unpacked mysql-server-5.1 5.1.39-1
2009-10-17 22:34:51 status unpacked mysql-server-5.1 5.1.39-1
2009-10-17 22:34:51 status unpacked mysql-server-5.1 5.1.39-1
2009-10-17 22:34:51 status unpacked mysql-server-5.1 5.1.39-1
2009-10-17 22:34:51 status unpacked mysql-server-5.1 5.1.39-1
2009-10-17 22:34:51 status half-configured mysql-server-5.1 5.1.39-1
2009-10-17 22:35:07 startup packages configure
2009-10-17 22:35:07 configure mysql-server-5.1 5.1.39-1 5.1.39-1
2009-10-17 22:35:07 status half-configured mysql-server-5.1 5.1.39-1

in kern.log I see this:
Oct 17 22:34:51 the-shire kernel: [ 1867.383827] mysqld[4109]: segfault at 4 ip b78f54ec sp bf9b1ca8 error 4 in ld-2.9.so[b78eb000+1c000]
Oct 17 22:34:51 the-shire kernel: [ 1867.392081] mysqld[4117]: segfault at 4 ip b77d04ec sp bfa6bc78 error 4 in ld-2.9.so[b77c6000+1c000]
Oct 17 22:34:52 the-shire kernel: [ 1867.479781] mysqld[4164]: segfault at 4 ip b790e4ec sp bfb89798 error 4 in ld-2.9.so[b7904000+1c000]
Oct 17 22:34:52 the-shire kernel: [ 1867.507309] mysqld[4177]: segfault at 4 ip b78a24ec sp bfc3cb08 error 4 in ld-2.9.so[b7898000+1c000]
Oct 17 22:34:52 the-shire kernel: [ 1867.517739] mysqld[4184]: segfault at 4 ip b79194ec sp bfaae198 error 4 in ld-2.9.so[b790f000+1c000]
Oct 17 22:34:52 the-shire kernel: [ 1867.520504] mysqld[4187]: segfault at 4 ip b78304ec sp bf97c5f8 error 4 in ld-2.9.so[b7826000+1c000]
Oct 17 22:34:52 the-shire kernel: [ 1867.522848] mysqld[4190]: segfault at 4 ip b78024ec sp bfdd33d8 error 4 in ld-2.9.so[b77f8000+1c000]
Oct 17 22:34:52 the-shire kernel: [ 1867.716524] mysqld[4219]: segfault at 4 ip b796e4ec sp bfb584b8 error 4 in ld-2.9.so[b7964000+1c000]
Oct 17 22:34:52 the-shire kernel: [ 1867.726975] mysqld[4230]: segfault at 4 ip b780d4ec sp bf86e098 error 4 in ld-2.9.so[b7803000+1c000]
Oct 17 22:34:52 the-shire kernel: [ 1867.892988] mysqld[4348]: segfault at 4 ip b79614ec sp bf88bf88 error 4 in ld-2.9.so[b7957000+1c000]
Oct 17 22:34:57 the-shire kernel: [ 1872.915980] __ratelimit: 4 callbacks suppressed
Oct 17 22:34:57 the-shire kernel: [ 1872.915990] mysqld[4391]: segfault at 4 ip b78044ec sp bfa51b78 error 4 in ld-2.9.so[b77fa000+1c000]
Oct 17 22:34:58 the-shire kernel: [ 1873.931017] mysqld[4400]: segfault at 4 ip b781c4ec sp bfaf7cb8 error 4 in ld-2.9.so[b7812000+1c000]
Oct 17 22:34:59 the-shire kernel: [ 1874.948153] mysqld[4409]: segfault at 4 ip b780a4ec sp bffb5e18 error 4 in ld-2.9.so[b7800000+1c000]
Oct 17 22:35:00 the-shire kernel: [ 1875.960174] mysqld[4418]: segfault at 4 ip b79654ec sp bf96be08 error 4 in ld-2.9.so[b795b000+1c000]
Oct 17 22:35:01 the-shire kernel: [ 1876.979954] mysqld[4427]: segfault at 4 ip b77bb4ec sp bfc5f778 error 4 in ld-2.9.so[b77b1000+1c000]
Oct 17 22:35:02 the-shire kernel: [ 1878.000572] mysqld[4436]: segfault at 4 ip b77fe4ec sp bff66338 error 4 in ld-2.9.so[b77f4000+1c000]
Oct 17 22:35:03 the-shire kernel: [ 1879.022440] mysqld[4445]: segfault at 4 ip b783e4ec sp bfd53458 error 4 in ld-2.9.so[b7834000+1c000]
Oct 17 22:35:04 the-shire kernel: [ 1880.040977] mysqld[4454]: segfault at 4 ip b78d54ec sp bfda6658 error 4 in ld-2.9.so[b78cb000+1c000]
Oct 17 22:35:05 the-shire kernel: [ 1881.062016] mysqld[4463]: segfault at 4 ip b77d54ec sp bf855b78 error 4 in ld-2.9.so[b77cb000+1c000]
Oct 17 22:35:06 the-shire kernel: [ 1882.081961] mysqld[4472]: segfault at 4 ip b77b34ec sp bfcdcd48 error 4 in ld-2.9.so[b77a9000+1c000]
Oct 17 22:35:06 the-shire kernel: [ 1882.100629] mysqld[4480]: segfault at 4 ip b78eb4ec sp bfe49ea8 error 4 in ld-2.9.so[b78e1000+1c000]
Oct 17 22:35:07 the-shire kernel: [ 1883.252329] mysqld[4530]: segfault at 4 ip b78d74ec sp bfaff998 error 4 in ld-2.9.so[b78cd000+1c000]
Oct 17 22:35:07 the-shire kernel: [ 1883.260179] mysqld[4538]: segfault at 4 ip b78ca4ec sp bff6d7a8 error 4 in ld-2.9.so[b78c0000+1c000]
Oct 17 22:35:07 the-shire kernel: [ 1883.315550] mysqld[4585]: segfault at 4 ip b78264ec sp bf87dfc8 error 4 in ld-2.9.so[b781c000+1c000]
Oct 17 22:35:07 the-shire kernel: [ 1883.330475] mysqld[4598]: segfault at 4 ip b78e24ec sp bf8344c8 error 4 in ld-2.9.so[b78d8000+1c000]
Oct 17 22:35:07 the-shire kernel: [ 1883.338114] mysqld[4605]: segfault at 4 ip b78f94ec sp bff8eef8 error 4 in ld-2.9.so[b78ef000+1c000]
Oct 17 22:35:10 the-shire kernel: [ 1885.523897] __ratelimit: 6 callbacks suppressed
Oct 17 22:35:10 the-shire kernel: [ 1885.523907] mysqld[4784]: segfault at 4 ip b78784ec sp bf96f708 error 4 in ld-2.9.so[b786e000+1c000]
Oct 17 22:35:11 the-shire kernel: [ 1886.543919] mysqld[4793]: segfault at 4 ip b77fe4ec sp bfc3ea18 error 4 in ld-2.9.so[b77f4000+1c000]
Oct 17 22:35:12 the-shire kernel: [ 1887.563959] mysqld[4802]: segfault at 4 ip b78d74ec sp bfe34068 error 4 in ld-2.9.so[b78cd000+1c000]
Oct 17 22:35:13 the-shire kernel: [ 1888.583994] mysqld[4811]: segfault at 4 ip b77914ec sp bfc46628 error 4 in ld-2.9.so[b7787000+1c000]
Oct 17 22:35:14 the-shire kernel: [ 1889.604121] mysqld[4820]: segfault at 4 ip b77ec4ec sp bfeb2568 error 4 in ld-2.9.so[b77e2000+1c000]
Oct 17 22:35:15 the-shire kernel: [ 1890.623250] mysqld[4829]: segfault at 4 ip b79414ec sp bfde0a78 error 4 in ld-2.9.so[b7937000+1c000]
Oct 17 22:35:16 the-shire kernel: [ 1891.642908] mysqld[4838]: segfault at 4 ip b791e4ec sp bfa8b908 error 4 in ld-2.9.so[b7914000+1c000]
Oct 17 22:35:17 the-shire kernel: [ 1892.663175] mysqld[4847]: segfault at 4 ip b787b4ec sp bfe129d8 error 4 in ld-2.9.so[b7871000+1c000]
Oct 17 22:35:18 the-shire kernel: [ 1893.683479] mysqld[4856]: segfault at 4 ip b78b84ec sp bf9f72c8 error 4 in ld-2.9.so[b78ae000+1c000]
Oct 17 22:35:19 the-shire kernel: [ 1894.704299] mysqld[4865]: segfault at 4 ip b790a4ec sp bfac9a98 error 4 in ld-2.9.so[b7900000+1c000]
Oct 17 22:35:20 the-shire kernel: [ 1895.723522] mysqld[4874]: segfault at 4 ip b78da4ec sp bfc07f08 error 4 in ld-2.9.so[b78d0000+1c000]
Oct 17 22:35:21 the-shire kernel: [ 1896.744193] mysqld[4883]: segfault at 4 ip b78544ec sp bfbd45f8 error 4 in ld-2.9.so[b784a000+1c000]
Oct 17 22:35:22 the-shire kernel: [ 1897.763401] mysqld[4892]: segfault at 4 ip b789e4ec sp bf94c428 error 4 in ld-2.9.so[b7894000+1c000]
Oct 17 22:35:22 the-shire kernel: [ 1897.782646] mysqld[4900]: segfault at 4 ip b778e4ec sp bfbfa838 error 4 in ld-2.9.so[b7784000+1c000]

and syslog reports essentially the same as in the original bug report of which only date/time/kerneltime/memory addresses are different.

Reverting back to 5.1.37-2 (and putting it on hold) resolves the issue, ie MySQL server starts again.
I'm not (actively) using it, it's brought in as a dependency from akonadi-server (KDE) and since korganizer depends on it, I didn't try purging and reinstalling it. 

So it's really reproducible on my system, but I don't know what's causing it.

If you want me to do (specific) test, let me know.

Regards,
  Diederik