Package: cups Version: 1.3.8-1lenny1 Severity: grave Justification: renders package unusable
Upon upgrading to 1.3.8-1lenny1, cups suddenly stopped printing. Going to http://localhost:631 produced the expected page, but trying to access any of the tabs to check on printers or queues just gave a 'waiting for localhost' response on the browser. I tried removing the package and re-installing. No change in behavior. Following some hints from other bug reports, I set "LogLevel debug" in the cupsd.conf file. Accessing the web interface and then selecting the 'printers' tab gives the following in /var/log/error_log D [11/Oct/2008:08:31:37 -0400] cupsdAcceptClient: 7 from localhost:631 (IPv4) D [11/Oct/2008:08:31:37 -0400] Report: clients=1 D [11/Oct/2008:08:31:37 -0400] Report: jobs=0 D [11/Oct/2008:08:31:37 -0400] Report: jobs-active=0 D [11/Oct/2008:08:31:37 -0400] Report: printers=0 D [11/Oct/2008:08:31:37 -0400] Report: printers-implicit=0 D [11/Oct/2008:08:31:37 -0400] Report: stringpool-string-count=170 D [11/Oct/2008:08:31:37 -0400] Report: stringpool-alloc-bytes=4976 D [11/Oct/2008:08:31:37 -0400] Report: stringpool-total-bytes=3720 D [11/Oct/2008:08:31:37 -0400] cupsdReadClient: 7 GET /printers/ HTTP/1.1 D [11/Oct/2008:08:31:37 -0400] cupsdAuthorize: No authentication data provided. D [11/Oct/2008:08:31:37 -0400] [CGI] /usr/lib/cups/cgi-bin/printers.cgi started - PID = 28005 I [11/Oct/2008:08:31:37 -0400] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=28005) D [11/Oct/2008:08:31:37 -0400] cupsdSendCommand: 7 file=8 E [11/Oct/2008:08:31:37 -0400] PID 28005 (/usr/lib/cups/cgi-bin/printers.cgi) stopped with status 22! D [11/Oct/2008:08:31:37 -0400] [CGI] /usr/lib/cups/cgi-bin/printers.cgi: Permission denied I ran /usr/lib/cups/cgi-bin/printers.cgi from the command line both as root and as a regular user. Both times I get html output (omitted for brevity of report) Running 'strace' on the cupsd binary gives the following when the 'Printers' tab is selected on the web interface. recv(11, "GET /printers/ HTTP/1.1\r\nHost: lo"..., 2048, 0) = 417 time(NULL) = 1223728808 time(NULL) = 1223728808 time(NULL) = 1223728808 time(NULL) = 1223728808 write(2, "D [11/Oct/2008:08:40:08 -0400] cu"..., 75) = 75 time(NULL) = 1223728808 time(NULL) = 1223728808 time(NULL) = 1223728808 time(NULL) = 1223728808 time(NULL) = 1223728808 time(NULL) = 1223728808 time(NULL) = 1223728808 time(NULL) = 1223728808 time(NULL) = 1223728808 time(NULL) = 1223728808 time(NULL) = 1223728808 write(2, "D [11/Oct/2008:08:40:08 -0400] cu"..., 80) = 80 pipe([13, 14]) = 0 fcntl64(13, F_GETFD) = 0 fcntl64(13, F_SETFD, FD_CLOEXEC) = 0 fcntl64(14, F_GETFD) = 0 fcntl64(14, F_SETFD, FD_CLOEXEC) = 0 access("/usr/lib/cups/cgi-bin/printers.cgi", X_OK) = 0 rt_sigprocmask(SIG_BLOCK, [TERM CHLD], [], 8) = 0 clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0xb79e0718) = 28019 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0 time(NULL) = 1223728808 write(2, "D [11/Oct/2008:08:40:08 -0400] [C"..., 94) = 94 close(14) = 0 time(NULL) = 1223728808 write(2, "I [11/Oct/2008:08:40:08 -0400] St"..., 88) = 88 time(NULL) = 1223728808 write(2, "D [11/Oct/2008:08:40:08 -0400] cu"..., 60) = 60 fcntl64(13, F_GETFD) = 0x1 (flags FD_CLOEXEC) fcntl64(13, F_SETFD, FD_CLOEXEC) = 0 write(9, "localhost - - [11/Oct/2008:08:40:"..., 79) = 79 time(NULL) = 1223728808 time(NULL) = 1223728808 poll([{fd=0, events=POLLIN}, {fd=3, events=POLLIN}, {fd=4, events=POLLIN}, {fd=5, events=POLLIN}, {fd=7, events=POLLIN}, {fd=8, events=POLLIN}, {fd=10, events=POLLIN}, {fd=11, events=POLLIN}, {fd=12, events=POLLIN}, {fd=13, events=POLLIN}, {fd=16, events=POLLIN}], 11, 1000) = 2 ([{fd=5, revents=POLLIN}, {fd=13, revents=POLLHUP}]) --- SIGCHLD (Child exited) @ 0 (0) --- sigreturn() = ? (mask now []) read(5, "/usr/lib/cups/cgi-bin/printers.cg"..., 1023) = 54 time(NULL) = 1223728808 write(2, "D [11/Oct/2008:08:40:08 -0400] [C"..., 91) = 91 epoll_ctl(-1, EPOLL_CTL_DEL, 13, {EPOLLRDNORM|EPOLLRDBAND|EPOLLWRBAND|EPOLLMSG|EPOLLERR|0x8a29800, {u32=3213176700, u64=13248886471035006844}}) = -1 EBADF (Bad file descriptor) close(-1) = -1 EBADF (Bad file descriptor) time(NULL) = 1223728808 waitpid(-1, [{WIFEXITED(s) && WEXITSTATUS(s) == 22}], WNOHANG) = 28019 time(NULL) = 1223728808 write(2, "E [11/Oct/2008:08:40:08 -0400] PI"..., 102) = 102 waitpid(-1, 0xbf853138, WNOHANG) = -1 ECHILD (No child processes) poll([{fd=0, events=POLLIN}, {fd=3, events=POLLIN}, {fd=4, events=POLLIN}, {fd=5, events=POLLIN}, {fd=7, events=POLLIN}, {fd=8, events=POLLIN}, {fd=10, events=POLLIN}, {fd=11, events=POLLOUT}, {fd=12, events=POLLIN}, {fd=16, events=POLLIN}], 10, 1000) = 1 ([{fd=11, revents=POLLOUT}]) read(13, ""..., 16384) = 0 write(9, "localhost - - [11/Oct/2008:08:40:"..., 79) = 79 epoll_ctl(-1, EPOLL_CTL_DEL, 13, {EPOLLRDNORM|EPOLLWRNORM|EPOLLWRBAND|EPOLLMSG|0x8a6d800, {u32=4278845440, u64=578866397829529600}}) = -1 EBADF (Bad file descriptor) close(-1) = -1 EBADF (Bad file descriptor) kill(28019, SIGTERM) = -1 ESRCH (No such process) close(13) = 0 time(NULL) = 1223728808 time(NULL) = 1223728808 poll([{fd=0, events=POLLIN}, {fd=3, events=POLLIN}, {fd=4, events=POLLIN}, {fd=5, events=POLLIN}, {fd=7, events=POLLIN}, {fd=8, events=POLLIN}, {fd=10, events=POLLIN}, {fd=11, events=POLLIN}, {fd=12, events=POLLIN}, {fd=16, events=POLLIN}], 10, 1000) = 0 (Timeout) time(NULL) = 1223728809 time(NULL) = 1223728809 time(NULL) = 1223728809 poll([{fd=0, events=POLLIN}, {fd=3, events=POLLIN}, {fd=4, events=POLLIN}, {fd=5, events=POLLIN}, {fd=7, events=POLLIN}, {fd=8, events=POLLIN}, {fd=10, events=POLLIN}, {fd=11, events=POLLIN}, {fd=12, events=POLLIN}, {fd=16, events=POLLIN}], 10, 269000) = 5 ([{fd=7, revents=POLLIN}, {fd=8, revents=POLLIN}, {fd=10, revents=POLLIN}, {fd=12, revents=POLLIN}, {fd=16, revents=POLLIN}]) poll([{fd=7, events=POLLIN}], 1, 10000) = 1 ([{fd=7, revents=POLLIN}]) recv(7, ""..., 2048, 0) = 0 time(NULL) = 1223728851 write(2, "D [11/Oct/2008:08:40:51 -0400] cu"..., 51) = 51 epoll_ctl(-1, EPOLL_CTL_DEL, 7, {EPOLLOUT|EPOLLERR|EPOLLET|0x37dd8000, {u32=144891224, u64=13800323211795291480}}) = -1 EBADF (Bad file descriptor) close(-1) = -1 EBADF (Bad file descriptor) close(7) = 0 poll([{fd=8, events=POLLIN}], 1, 10000) = 1 ([{fd=8, revents=POLLIN}]) recv(8, ""..., 2048, 0) = 0 time(NULL) = 1223728851 write(2, "D [11/Oct/2008:08:40:51 -0400] cu"..., 51) = 51 epoll_ctl(-1, EPOLL_CTL_DEL, 8, {EPOLLOUT|EPOLLERR|EPOLLET|0x37dd8000, {u32=144891224, u64=13800323211795291480}}) = -1 EBADF (Bad file descriptor) close(-1) = -1 EBADF (Bad file descriptor) close(8) = 0 poll([{fd=10, events=POLLIN}], 1, 10000) = 1 ([{fd=10, revents=POLLIN}]) recv(10, ""..., 2048, 0) = 0 time(NULL) = 1223728851 write(2, "D [11/Oct/2008:08:40:51 -0400] cu"..., 52) = 52 epoll_ctl(-1, EPOLL_CTL_DEL, 10, {EPOLLOUT|EPOLLERR|EPOLLET|0x37dd8000, {u32=144891224, u64=13800323211795291480}}) = -1 EBADF (Bad file descriptor) close(-1) = -1 EBADF (Bad file descriptor) close(10) = 0 poll([{fd=12, events=POLLIN}], 1, 10000) = 1 ([{fd=12, revents=POLLIN}]) recv(12, ""..., 2048, 0) = 0 time(NULL) = 1223728851 write(2, "D [11/Oct/2008:08:40:51 -0400] cu"..., 52) = 52 epoll_ctl(-1, EPOLL_CTL_DEL, 12, {EPOLLOUT|EPOLLERR|EPOLLET|0x37dd8000, {u32=144891224, u64=13800323211795291480}}) = -1 EBADF (Bad file descriptor) close(-1) = -1 EBADF (Bad file descriptor) close(12) = 0 poll([{fd=16, events=POLLIN}], 1, 10000) = 1 ([{fd=16, revents=POLLIN}]) recv(16, ""..., 2048, 0) = 0 time(NULL) = 1223728851 write(2, "D [11/Oct/2008:08:40:51 -0400] cu"..., 52) = 52 epoll_ctl(-1, EPOLL_CTL_DEL, 16, {EPOLLOUT|EPOLLERR|EPOLLET|0x37dd8000, {u32=144891224, u64=13800323211795291480}}) = -1 EBADF (Bad file descriptor) close(-1) = -1 EBADF (Bad file descriptor) close(16) = 0 time(NULL) = 1223728851 time(NULL) = 1223728851 time(NULL) = 1223728851 write(2, "D [11/Oct/2008:08:40:51 -0400] Re"..., 49) = 49 time(NULL) = 1223728851 write(2, "D [11/Oct/2008:08:40:51 -0400] Re"..., 46) = 46 time(NULL) = 1223728851 write(2, "D [11/Oct/2008:08:40:51 -0400] Re"..., 53) = 53 time(NULL) = 1223728851 write(2, "D [11/Oct/2008:08:40:51 -0400] Re"..., 50) = 50 time(NULL) = 1223728851 write(2, "D [11/Oct/2008:08:40:51 -0400] Re"..., 59) = 59 time(NULL) = 1223728851 write(2, "D [11/Oct/2008:08:40:51 -0400] Re"..., 67) = 67 time(NULL) = 1223728851 write(2, "D [11/Oct/2008:08:40:51 -0400] Re"..., 67) = 67 time(NULL) = 1223728851 write(2, "D [11/Oct/2008:08:40:51 -0400] Re"..., 67) = 67 poll([{fd=0, events=POLLIN}, {fd=3, events=POLLIN}, {fd=4, events=POLLIN}, {fd=5, events=POLLIN}, {fd=11, events=POLLIN}], 5, 1000) = 0 (Timeout) time(NULL) = 1223728852 time(NULL) = 1223728852 time(NULL) = 1223728852 At this point, I am not sure where to start looking to resolve the problems myself. The printing system is unusable in this state, hence the grave level report. If there is more information that is required, I will be happy to provide that, just let me know what is needed. -- System Information: Debian Release: lenny/sid APT prefers testing APT policy: (500, 'testing') Architecture: i386 (i686) Kernel: Linux 2.6.26-1-686 (SMP w/1 CPU core) Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8) Shell: /bin/sh linked to /bin/bash Versions of packages cups depends on: ii adduser 3.110 add and remove users and groups ii cups-common 1.3.8-1lenny1 Common UNIX Printing System(tm) - ii debconf [debconf-2.0] 1.5.22 Debian configuration management sy ii ghostscript 8.62.dfsg.1-3.1 The GPL Ghostscript PostScript/PDF ii libavahi-compat-libdn 0.6.22-3 Avahi Apple Bonjour compatibility ii libc6 2.7-13 GNU C Library: Shared libraries ii libcups2 1.3.8-1lenny1 Common UNIX Printing System(tm) - ii libcupsimage2 1.3.8-1lenny1 Common UNIX Printing System(tm) - ii libdbus-1-3 1.2.1-3 simple interprocess messaging syst ii libgnutls26 2.4.1-1 the GNU TLS library - runtime libr ii libkrb53 1.6.dfsg.4~beta1-4 MIT Kerberos runtime libraries ii libldap-2.4-2 2.4.10-3 OpenLDAP libraries ii libpam0g 1.0.1-4 Pluggable Authentication Modules l ii libpaper1 1.1.23+nmu1 library for handling paper charact ii libslp1 1.2.1-7.4 OpenSLP libraries ii lsb-base 3.2-20 Linux Standard Base 3.2 init scrip ii perl-modules 5.10.0-15 Core Perl modules ii procps 1:3.2.7-8 /proc file system utilities ii ssl-cert 1.0.23 simple debconf wrapper for OpenSSL ii xpdf-utils [poppler-u 3.02-1.4 Portable Document Format (PDF) sui Versions of packages cups recommends: ii avahi-utils 0.6.22-3 Avahi browsing, publishing and dis ii cups-client 1.3.8-1lenny1 Common UNIX Printing System(tm) - ii foomatic-filters 3.0.2-20080211-3.1 OpenPrinting printer support - fil ii smbclient 2:3.2.3-1 a LanManager-like simple client fo Versions of packages cups suggests: pn cups-bsd <none> (no description available) pn cups-driver-gutenprint <none> (no description available) pn cups-pdf <none> (no description available) ii foomatic-db 20080211-2+nmu1 OpenPrinting printer support - dat ii foomatic-db-engine 3.0.2-20080211-1 OpenPrinting printer support - pro pn hplip <none> (no description available) pn xpdf-korean | xpdf-japa <none> (no description available) -- debconf information: * cupsys/raw-print: false * cupsys/backend: ipp, lpd, parallel, scsi, serial, socket, usb, snmp, dnssd -- To UNSUBSCRIBE, email to [EMAIL PROTECTED] with a subject of "unsubscribe". Trouble? Contact [EMAIL PROTECTED]