Bug#549050: cupsd segfaults on SIGHUP
Package: cups
Version: 1.4.1-3
Severity: important
(The problem appeared with 1.4.1-2.)
Platform:
AMD Phenom9850 Quad-Core Processor (2500 MHz)
Linux 2.6.30-rc5-00065-g437a53d (up 141 days)
8 GB RAM, 64-bit kernel.
32-bit Debian userland.
Twice now, cupsd has died at quarter to 6, local time:
Sep 29 05:43:59 $HOST kernel: cupsd[17719]: segfault at ea04a880 ip 00000000f7d5d3e4 sp 00000000ffc63a10 error 4 in libcups.so.2[f7d4e000+41000]
Sep 30 05:42:19 $HOST kernel: cupsd[26674]: segfault at 69726f69 ip 0000000069726f69 sp 00000000ffbfe11c error 14 in libnss_files-2.9.so[f7918000+a000]
The first time, I didn't look into it too deeply. The second time,
the similarity was too great to ignore.
On investigation, it's being caused by the SIGHUP sent from
/etc/init.d/cups reload
initiated by /etc/logrotate.d/cups. I can trigger it manually:
Sep 30 09:35:39 $HOST kernel: cupsd[18645]: segfault at 4a3c0a3e ip 000000004a3c0a3e sp 00000000ffe7e27c error 14 in libnss_files-2.9.so[f7940000+a000]
The IP does indeed vary:
cupsd[26674]: segfault at 69726f69 ip 0000000069726f69 sp 00000000ffbfe11c error 14 in libnss_files-2.9.so[f7918000+a000]
cupsd[18645]: segfault at 4a3c0a3e ip 000000004a3c0a3e sp 00000000ffe7e27c error 14 in libnss_files-2.9.so[f7940000+a000]
The CUPS log files is similar each time:
I [29/Sep/2009:05:43:58 +0000] cupsdDefaultRIPCacheSize: 2044719k
I [29/Sep/2009:05:43:58 +0000] Listening to ::1:631 (IPv6)
I [29/Sep/2009:05:43:58 +0000] Listening to 127.0.0.1:631 (IPv4)
I [29/Sep/2009:05:43:58 +0000] Listening to www.xxx.yyy.zzz:631 (IPv4)
I [29/Sep/2009:05:43:58 +0000] Listening to /var/run/cups/cups.sock (Domain)
I [29/Sep/2009:05:43:58 +0000] Remote access is enabled.
I [29/Sep/2009:05:43:58 +0000] Loaded configuration file "/etc/cups/cupsd.conf"
I [29/Sep/2009:05:43:58 +0000] Using default TempDir of /var/spool/cups/tmp...
I [29/Sep/2009:05:43:58 +0000] Configured for up to 100 clients.
I [29/Sep/2009:05:43:58 +0000] Allowing up to 100 client connections per host.
I [29/Sep/2009:05:43:58 +0000] Using policy "default" as the default!
I [29/Sep/2009:05:43:58 +0000] Full reload is required.
I [29/Sep/2009:05:43:58 +0000] Saving job cache file "/var/cache/cups/job.cache"...
(Restart begins here)
I [30/Sep/2009:05:42:19 +0000] cupsdDefaultRIPCacheSize: 2044719k
I [30/Sep/2009:05:42:19 +0000] Listening to ::1:631 (IPv6)
I [30/Sep/2009:05:42:19 +0000] Listening to 127.0.0.1:631 (IPv4)
I [30/Sep/2009:05:42:19 +0000] Listening to www.xxx.yyy.zzz:631 (IPv4)
I [30/Sep/2009:05:42:19 +0000] Listening to /var/run/cups/cups.sock (Domain)
I [30/Sep/2009:05:42:19 +0000] Remote access is enabled.
I [30/Sep/2009:05:42:19 +0000] Loaded configuration file "/etc/cups/cupsd.conf"
I [30/Sep/2009:05:42:19 +0000] Using default TempDir of /var/spool/cups/tmp...
I [30/Sep/2009:05:42:19 +0000] Configured for up to 100 clients.
I [30/Sep/2009:05:42:19 +0000] Allowing up to 100 client connections per host.
I [30/Sep/2009:05:42:19 +0000] Using policy "default" as the default!
I [30/Sep/2009:05:42:19 +0000] Full reload is required.
I [30/Sep/2009:05:42:19 +0000] Saving job cache file "/var/cache/cups/job.cache"...
I [30/Sep/2009:09:35:39 +0000] cupsdDefaultRIPCacheSize: 2044719k
I [30/Sep/2009:09:35:39 +0000] Listening to ::1:631 (IPv6)
I [30/Sep/2009:09:35:39 +0000] Listening to 127.0.0.1:631 (IPv4)
I [30/Sep/2009:09:35:39 +0000] Listening to www.xxx.yyy.zzz:631 (IPv4)
I [30/Sep/2009:09:35:39 +0000] Listening to /var/run/cups/cups.sock (Domain)
I [30/Sep/2009:09:35:39 +0000] Remote access is enabled.
I [30/Sep/2009:09:35:39 +0000] Loaded configuration file "/etc/cups/cupsd.conf"
I [30/Sep/2009:09:35:39 +0000] Using default TempDir of /var/spool/cups/tmp...
I [30/Sep/2009:09:35:39 +0000] Configured for up to 100 clients.
I [30/Sep/2009:09:35:39 +0000] Allowing up to 100 client connections per host.
I [30/Sep/2009:09:35:39 +0000] Using policy "default" as the default!
I [30/Sep/2009:09:35:39 +0000] Full reload is required.
I [30/Sep/2009:09:35:39 +0000] Saving job cache file "/var/cache/cups/job.cache"...
Versions of dependent packages as of right now:
Desired=Unknown/Install/Remove/Purge/Hold
| Status=Not/Inst/Cfg-files/Unpacked/Failed-cfg/Half-inst/trig-aWait/Trig-pend
|/ Err?=(none)/Reinst-required (Status,Err: uppercase=bad)
||/ Name Version Description
+++-=======================-==================-================================
ii adduser 3.111 add and remove users and groups
ii bc 1.06.94-3.1 The GNU bc arbitrary precision c
ii cups 1.4.1-3 Common UNIX Printing System(tm)
ii cups-client 1.4.1-3 Common UNIX Printing System(tm)
ii cups-common 1.4.1-3 Common UNIX Printing System(tm)
ii cups-driver-gutenprint 5.2.4-1 printer drivers for CUPS
ii debconf 1.5.27 Debian configuration management
un debconf-2.0 <none> (no description available)
ii foomatic-filters 4.0-20090509-1 OpenPrinting printer support - f
ii ghostscript 8.70~dfsg-2 The GPL Ghostscript PostScript/P
ii ghostscript-cups 8.70~dfsg-2 The GPL Ghostscript PostScript/P
ii libavahi-client3 0.6.25-1 Avahi client library
ii libavahi-common3 0.6.25-1 Avahi common library
ii libc6 2.9-26 GNU C Library: Shared libraries
ii libcups2 1.4.1-3 Common UNIX Printing System(tm)
ii libcupscgi1 1.4.1-3 Common UNIX Printing System(tm)
ii libcupsdriver1 1.4.1-3 Common UNIX Printing System(tm)
ii libcupsimage2 1.4.1-3 Common UNIX Printing System(tm)
ii libcupsmime1 1.4.1-3 Common UNIX Printing System(tm)
ii libcupsppdc1 1.4.1-3 Common UNIX Printing System(tm)
ii libdbus-1-3 1.2.16-2 simple interprocess messaging sy
ii libgcc1 1:4.4.1-4 GCC support library
ii libgnutls26 2.8.4-1 the GNU TLS library - runtime li
ii libgssapi-krb5-2 1.7dfsg~beta3-1 MIT Kerberos runtime libraries -
ii libijs-0.35 0.35-7 IJS raster image transport proto
ii libkrb5-3 1.7dfsg~beta3-1 MIT Kerberos runtime libraries
ii libldap-2.4-2 2.4.17-2 OpenLDAP libraries
ii libpam0g 1.1.0-4 Pluggable Authentication Modules
ii libpaper1 1.1.23+nmu1 library for handling paper chara
ii libpoppler4 0.10.6-1 PDF rendering library
ii libslp1 1.2.1-7.6 OpenSLP libraries
ii libstdc++6 4.4.1-4 The GNU Standard C++ Library v3
ii libusb-0.1-4 2:0.1.12-13 userspace USB programming librar
ii lsb-base 3.2-23 Linux Standard Base 3.2 init scr
ii perl-modules 5.10.1-3 Core Perl modules
un poppler-utils <none> (no description available)
ii procps 1:3.2.8-1.1 /proc file system utilities
ii ssl-cert 1.0.23 simple debconf wrapper for OpenS
ii ttf-freefont 20090104-4 Freefont Serif, Sans and Mono Tr
ii xpdf-utils 3.02-1.4+lenny1 Portable Document Format (PDF) s
ii zlib1g 1:1.2.3.3.dfsg-15 compression library - runtime
However, there have been recent upgrades. First on the 28th, and then
on the 29th I saw a CUPS upgrade and let it go through hoping it might
have something to do with the problem.
Note that this crash did NOT occur on the morning of the 28th or
earlier, so it had to be something that changed on the 28th.
Packages installed on the 28th:
2009-09-28 17:10:21 upgrade apt 0.7.23.1 0.7.24
2009-09-28 17:10:29 upgrade gpgv 1.4.10-1 1.4.10-2
2009-09-28 17:10:34 upgrade gnupg 1.4.10-1 1.4.10-2
2009-09-28 17:10:42 upgrade apt-utils 0.7.23.1 0.7.24
2009-09-28 17:10:43 upgrade libgnutls-dev 2.8.3-3 2.8.4-1
2009-09-28 17:10:43 upgrade libgnutls26 2.8.3-3 2.8.4-1
2009-09-28 17:10:44 upgrade libxml2-dev 2.7.4.dfsg-2 2.7.5.dfsg-1
2009-09-28 17:10:44 upgrade libxml2 2.7.4.dfsg-2 2.7.5.dfsg-1
2009-09-28 17:10:44 upgrade python2.5-dev 2.5.4-1 2.5.4-2
2009-09-28 17:10:45 upgrade python2.5 2.5.4-1 2.5.4-2
2009-09-28 17:10:47 upgrade python2.5-minimal 2.5.4-1 2.5.4-2
2009-09-28 17:10:48 upgrade linux-sound-base 1.0.21+dfsg-1 1.0.21+dfsg-2
2009-09-28 17:10:48 upgrade alsa-base 1.0.21+dfsg-1 1.0.21+dfsg-2
2009-09-28 17:10:49 upgrade apt-doc 0.7.23.1 0.7.24
2009-09-28 17:10:49 upgrade autoconf 2.64-2 2.64-4
2009-09-28 17:10:49 upgrade binutils-multiarch 2.19.91.20090923-1 2.19.91.20090927-1
2009-09-28 17:10:52 upgrade binutils-dev 2.19.91.20090923-1 2.19.91.20090927-1
2009-09-28 17:10:53 upgrade binutils 2.19.91.20090923-1 2.19.91.20090927-1
2009-09-28 17:10:54 upgrade binutils-doc 2.19.91.20090923-1 2.19.91.20090927-1
2009-09-28 17:10:55 upgrade cdbs 0.4.61 0.4.61+nmu1
2009-09-28 17:10:55 upgrade djvulibre-desktop 3.5.22-3 3.5.22-4
2009-09-28 17:10:57 upgrade dovecot-imapd 1:1.2.5-1 1:1.2.5-2
2009-09-28 17:10:58 upgrade dovecot-common 1:1.2.5-1 1:1.2.5-2
2009-09-28 17:10:59 upgrade fontforge 0.0.20090914-1 0.0.20090923-1
2009-09-28 17:11:00 upgrade libfontforge1 0.0.20090914-1 0.0.20090923-1
2009-09-28 17:11:00 upgrade libgdraw4 0.0.20090914-1 0.0.20090923-1
2009-09-28 17:11:02 upgrade gnome-desktop-data 2.26.1-1 2.28.0-1
2009-09-28 17:11:03 upgrade gnome-devel-docs 2.26.2-1 2.28.0-1
2009-09-28 17:11:04 upgrade python-libxml2 2.7.4.dfsg-2 2.7.5.dfsg-1
2009-09-28 17:11:05 upgrade libxml2-utils 2.7.4.dfsg-2 2.7.5.dfsg-1
2009-09-28 17:11:05 upgrade libxslt1.1 1.1.24-2 1.1.26-1
2009-09-28 17:11:05 upgrade xsltproc 1.1.24-2 1.1.26-1
2009-09-28 17:11:07 upgrade gnome-doc-utils 0.17.4-2 0.18.0-1
2009-09-28 17:11:08 install gnupg-curl <none> 1.4.10-2
2009-09-28 17:11:09 upgrade gnuplot-nox 4.2.5-3 4.2.6-1
2009-09-28 17:11:09 upgrade gnuplot-x11 4.2.5-3 4.2.6-1
2009-09-28 17:11:09 upgrade gnuplot 4.2.5-3 4.2.6-1
2009-09-28 17:11:09 upgrade gnuplot-doc 4.2.5-3 4.2.6-1
2009-09-28 17:11:10 upgrade gnutls-bin 2.8.3-3 2.8.4-1
2009-09-28 17:11:10 upgrade libbonobo2-dev 2.24.1-1 2.24.2-1
2009-09-28 17:11:12 upgrade libbonobo2-0 2.24.1-1 2.24.2-1
2009-09-28 17:11:13 upgrade libbonobo2-common 2.24.1-1 2.24.2-1
2009-09-28 17:11:13 upgrade libbonoboui2-dev 2.24.1-1 2.24.2-1
2009-09-28 17:11:13 upgrade libbonoboui2-common 2.24.1-1 2.24.2-1
2009-09-28 17:11:14 upgrade libbonoboui2-0 2.24.1-1 2.24.2-1
2009-09-28 17:11:14 install libcanberra0 <none> 0.12-1.1
2009-09-28 17:11:14 install libcanberra-gtk0 <none> 0.12-1.1
2009-09-28 17:11:14 install libcanberra-gtk-module <none> 0.12-1.1
2009-09-28 17:11:14 upgrade libdjvulibre-text 3.5.22-3 3.5.22-4
2009-09-28 17:11:15 upgrade libdjvulibre21 3.5.22-3 3.5.22-4
2009-09-28 17:11:15 install libfile-copy-recursive-perl <none> 0.38-1
2009-09-28 17:11:15 upgrade libgnome-desktop-dev 2.26.1-1 2.28.0-1
2009-09-28 17:11:16 upgrade libgnome-desktop-2-11 2.26.1-1 2.28.0-1
2009-09-28 17:11:17 upgrade libgnome-menu-dev 2.26.1-2 2.28.0-1
2009-09-28 17:11:17 upgrade libgnome-menu2 2.26.1-2 2.28.0-1
2009-09-28 17:11:18 upgrade libgnomeui-dev 2.24.1-1 2.24.2-1
2009-09-28 17:11:18 upgrade libgnomeui-common 2.24.1-1 2.24.2-1
2009-09-28 17:11:18 upgrade libgnomeui-0 2.24.1-1 2.24.2-1
2009-09-28 17:11:19 upgrade libgtkhtml3.14-dev 3.28.0-2 3.28.0-3
2009-09-28 17:11:19 upgrade libgtkhtml3.14-19 3.28.0-2 3.28.0-3
2009-09-28 17:11:19 upgrade liblua50 5.0.3-3 5.0.3-4
2009-09-28 17:11:20 upgrade liblualib50 5.0.3-3 5.0.3-4
2009-09-28 17:11:20 upgrade libmetacity-private0 1:2.26.0-3 1:2.28.0-1
2009-09-28 17:11:20 upgrade metacity-common 1:2.26.0-3 1:2.28.0-1
2009-09-28 17:11:24 upgrade liboop4 1.0-5 1.0-6
2009-09-28 17:11:24 upgrade libpulse-dev 0.9.17-1 0.9.18-1
2009-09-28 17:11:24 upgrade libpulse-mainloop-glib0 0.9.17-1 0.9.18-1
2009-09-28 17:11:25 upgrade libpulse-browse0 0.9.17-1 0.9.18-1
2009-09-28 17:11:25 upgrade libpulse0 0.9.17-1 0.9.18-1
2009-09-28 17:11:25 upgrade libsane 1.0.20-7 1.0.20-8
2009-09-28 17:11:27 upgrade libtheora-dev 1.1~beta3-1 1.1.0-1
2009-09-28 17:11:28 upgrade libtheora0 1.1~beta3-1 1.1.0-1
2009-09-28 17:11:28 upgrade libudev0 0.141-2 146-3
2009-09-28 17:11:28 upgrade libunique-1.0-0 1.0.8-1 1.1.2-2
2009-09-28 17:11:28 upgrade libvte-common 1:0.22.0-2 1:0.22.1-2
2009-09-28 17:11:29 upgrade libvte-dev 1:0.22.0-2 1:0.22.1-2
2009-09-28 17:11:29 upgrade libvte9 1:0.22.0-2 1:0.22.1-2
2009-09-28 17:11:29 upgrade python-vte 1:0.22.0-2 1:0.22.1-2
2009-09-28 17:11:30 upgrade libwww-perl 5.831-1 5.832-1
2009-09-28 17:11:30 upgrade linux-libc-dev 2.6.30-7 2.6.30-8
2009-09-28 17:11:33 upgrade python-gtksourceview2 2.6.0-1 2.8.0-1
2009-09-28 17:11:33 upgrade python2.5-examples 2.5.4-1 2.5.4-2
2009-09-28 17:11:34 upgrade update-inetd 4.34 4.35
2009-09-28 17:11:34 upgrade sane-utils 1.0.20-7 1.0.20-8
2009-09-28 17:11:35 upgrade squid-langpack 20090921-1 20090921-2
2009-09-28 17:11:36 upgrade xserver-common 2:1.6.3.901-1 2:1.6.4-1
2009-09-28 17:11:36 upgrade xserver-xorg-core 2:1.6.3.901-1 2:1.6.4-1
2009-09-28 17:11:36 upgrade xserver-xorg-dev 2:1.6.3.901-1 2:1.6.4-1
2009-09-28 17:11:37 upgrade xvfb 2:1.6.3.901-1 2:1.6.4-1
2009-09-28 17:11:37 upgrade zsh-beta 4.3.10-dev-1+20090916-1 4.3.10-dev-1+20090922-1
2009-09-28 17:11:39 upgrade zsh-beta-doc 4.3.10-dev-1+20090916-1 4.3.10-dev-1+20090922-1
And on the 29th:
2009-09-29 12:48:17 upgrade hostname 2.98 2.99
2009-09-29 12:48:22 upgrade perl-modules 5.10.0-25 5.10.1-3
2009-09-29 12:48:25 upgrade perl-doc 5.10.0-25 5.10.1-3
2009-09-29 12:48:27 upgrade libperl-dev 5.10.0-25 5.10.1-3
2009-09-29 12:48:27 upgrade libperl5.10 5.10.0-25 5.10.1-3
2009-09-29 12:48:29 upgrade perl 5.10.0-25 5.10.1-3
2009-09-29 12:48:31 upgrade perl-base 5.10.0-25 5.10.1-3
2009-09-29 12:48:40 upgrade libcups2-dev 1.4.1-2 1.4.1-3
2009-09-29 12:48:40 upgrade libcups2 1.4.1-2 1.4.1-3
2009-09-29 12:48:41 upgrade libcupscgi1 1.4.1-2 1.4.1-3
2009-09-29 12:48:41 upgrade libcupsdriver1 1.4.1-2 1.4.1-3
2009-09-29 12:48:41 upgrade libcupsimage2 1.4.1-2 1.4.1-3
2009-09-29 12:48:42 upgrade libcupsmime1 1.4.1-2 1.4.1-3
2009-09-29 12:48:42 upgrade libcupsppdc1 1.4.1-2 1.4.1-3
2009-09-29 12:48:44 upgrade cups-common 1.4.1-2 1.4.1-3
2009-09-29 12:48:45 upgrade cups-bsd 1.4.1-2 1.4.1-3
2009-09-29 12:48:46 upgrade cups-client 1.4.1-2 1.4.1-3
2009-09-29 12:48:46 upgrade cups 1.4.1-2 1.4.1-3
2009-09-29 12:48:48 upgrade cups-ppdc 1.4.1-2 1.4.1-3
2009-09-29 12:48:50 upgrade cupsddk 1.4.1-2 1.4.1-3
2009-09-29 12:48:50 upgrade fakeroot 1.13 1.13.1
2009-09-29 12:48:51 upgrade libcanberra0 0.12-1.1 0.18
2009-09-29 12:48:51 upgrade libcanberra-gtk0 0.12-1.1 0.18
2009-09-29 12:48:52 upgrade libcanberra-gtk-module 0.12-1.1 0.18
2009-09-29 12:48:52 upgrade mysql-common 5.1.37-2 5.1.39-1
2009-09-29 12:48:52 upgrade libmysqlclient16 5.1.37-2 5.1.39-1
2009-09-29 12:48:53 upgrade libmysqlclient-dev 5.1.37-2 5.1.39-1
2009-09-29 12:48:55 upgrade libpciaccess-dev 0.10.6-2 0.10.9-1
2009-09-29 12:48:56 upgrade libpciaccess0 0.10.6-2 0.10.9-1
2009-09-29 12:48:56 upgrade libpixman-1-dev 0.14.0-1 0.16.2-1
2009-09-29 12:48:56 upgrade libpixman-1-0 0.14.0-1 0.16.2-1
2009-09-29 12:48:57 upgrade ntop 3:3.3-11+b2 3:3.3-12
2009-09-29 12:49:00 upgrade sysstat 9.0.4-1 9.0.5-1
2009-09-29 12:49:01 upgrade x11proto-render-dev 2:0.9.3-2 2:0.11-1
2009-09-29 12:49:01 upgrade libytnef0 1.5-1.1 1.5-2
2009-09-29 12:49:01 upgrade ytnef 2.6-1 2.6-2
Tracing cupsd up to the crash, it ends with:
write(3, "I [30/Sep/2009:14:20:23 -0400] Saving job cache file \"/var/cache/cups/job.cache\"...\n", 84) = 84
getuid32() = 0
fchown32(5, 0, 7) = 0
fchmod(5, 0640) = 0
time(NULL) = 1254320423
stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=1267, ...}) = 0
write(5, "# Job cache file for CUPS v1.4.1\n# Written by cupsd on 2009-09-30 14:20\nNextJobId 112282\n<Job 111782>\nState 9\nPriority 50\nHoldUn"..., 4095) = 4095
write(5, ""..., 4089) = 4089
write(5, ""..., 4084) = 4084
write(5, ""..., 4091) = 4091
write(5, ""..., 4076) = 4076
write(5, ""..., 4080) = 4080
write(5, ""..., 4094) = 4094
write(5, ""..., 4095) = 4095
write(5, ""..., 4090) = 4090
write(5, ""..., 4096) = 4096
write(5, ""..., 4091) = 4091
write(5, ""..., 4094) = 4094
write(5, ""..., 4093) = 4093
write(5, ""..., 2720) = 2720
close(5) = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
writev(13, [{"l\4\1\1\n\0\0\0q\0\0\0o\0\0\0\1\1o\0\32\0\0\0/com/redhat/PrinterSpooler\0\0\0\0\0\0\2\1s\0\31\0\0\0com.redhat.PrinterSpooler\0\0\0\0\0\0\0\3\1s\0\f\0\0\0QueueChanged\0\0\0\0\10\1g\0\1s\0\0", 128}, {"\5\0\0\0aaaaa\0", 10}], 2) = 138
time(NULL) = 1254320423
time(NULL) = 1254320423
writev(13, [{""..., 128}, {"\2\0\0\0bb\0", 7}], 2) = 135
time(NULL) = 1254320423
writev(13, [{""..., 128}, {"\5\0\0\0ccccc\0", 10}], 2) = 138
time(NULL) = 1254320423
writev(13, [{""..., 128}, {"\6\0\0\0dddddd\0", 11}], 2) = 139
time(NULL) = 1254320423
writev(13, [{""..., 128}, {"\7\0\0\0eeeeeee\0", 12}], 2) = 140
time(NULL) = 1254320423
writev(13, [{""..., 128}, {"\2\0\0\0ff\0", 7}], 2) = 135
time(NULL) = 1254320423
--- SIGSEGV (Segmentation fault) @ 0 (0) ---
Trying to use ltrace results in the message "User defined signal 1" and
no log file. :-( I haven't figured that out yet.
Hopefully, there's enough information to track down the
problem anyway.
Reply to: