Bug#403336: marked as done (cupsys: Hangs when trying to add a newly found printer)

To: Frank Küster <frank@kuesterei.ch>
Subject: Bug#403336: marked as done (cupsys: Hangs when trying to add a newly found printer)
From: owner@bugs.debian.org (Debian Bug Tracking System)
Date: Thu, 11 Feb 2010 17:06:11 +0000
Message-id: <[🔎] handler.403336.D403336.126590782330174.ackdone@bugs.debian.org>
References: <87sk97ael0.fsf@alhambra.kuesterei.ch> <86tzzwf4rd.fsf@alhambra.kuesterei.ch>

Your message dated Thu, 11 Feb 2010 18:04:43 +0100
with message-id <87sk97ael0.fsf@alhambra.kuesterei.ch>
and subject line Re: cupsys: Hangs when trying to add a newly found printer
has caused the Debian Bug report #403336,
regarding cupsys: Hangs when trying to add a newly found printer
to be marked as done.

This means that you claim that the problem has been dealt with.
If this is not the case it is now your responsibility to reopen the
Bug report if necessary, and/or fix the problem forthwith.

(NB: If you are a system administrator and have no idea what this
message is talking about, this may indicate a serious mail system
misconfiguration somewhere. Please contact owner@bugs.debian.org
immediately.)


-- 
403336: http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=403336
Debian Bug Tracking System
Contact owner@bugs.debian.org with problems

--- Begin Message ---

To: Debian Bug Tracking System <submit@bugs.debian.org>
Subject: cupsys: Hangs when trying to add a newly found printer
From: Frank Küster <frank@debian.org>
Date: Sat, 16 Dec 2006 12:40:22 +0100
Message-id: <86tzzwf4rd.fsf@alhambra.kuesterei.ch>

Package: cupsys
Version: 1.2.7-1~bpo.1
Severity: important

I have experienced this three times, I think always with USB printers.
At http://localhost:631/admin, the printer is shown correctly as newly
found.  After clicking on "Add this printer", the browser displays
"Loading", but nothing happens, and cupsd and cups-driverd together eat
up all CPU (both about 48%, the rest is taken by Xorg and firefox-bin;
after clicking on stop both have near to 50%).

When I click on "Stop" in the browser, the CPU usage does not
drop. "/etc/init.d/cupsys restart" frees the CPU from the load.  These
are the last entries in access.log:

localhost - - [16/Dec/2006:12:28:44 +0100] "GET /admin HTTP/1.1" 200 0 - -
localhost - - [16/Dec/2006:12:28:44 +0100] "POST / HTTP/1.1" 200 1618 CUPS-Get-Devices -
localhost - - [16/Dec/2006:12:28:44 +0100] "GET /admin HTTP/1.1" 200 6462 - -
localhost - - [16/Dec/2006:12:28:46 +0100] "GET /images/button-add-this-printer.gif HTTP/1.1" 200 577 - -
localhost - - [16/Dec/2006:12:28:46 +0100] "GET /images/button-edit-configuration-file.gif HTTP/1.1" 200 699 - -
localhost - - [16/Dec/2006:12:28:46 +0100] "GET /images/button-view-access-log.gif HTTP/1.1" 200 638 - -
localhost - - [16/Dec/2006:12:28:46 +0100] "GET /images/button-view-error-log.gif HTTP/1.1" 200 554 - -
localhost - - [16/Dec/2006:12:28:46 +0100] "GET /images/button-view-page-log.gif HTTP/1.1" 200 590 - -
localhost - - [16/Dec/2006:12:28:46 +0100] "GET /images/button-change-settings.gif HTTP/1.1" 200 615 - -
localhost - - [16/Dec/2006:12:28:55 +0100] "GET /admin?op=add-printer&PRINTER_NAME=HP_LaserJet_1200_USB_1&PRINTER_LOCATION=Local+Printer&PRINTER_INFO=HP+LaserJet+1200&DEVICE_URI=usb://HP/LaserJet%25201200|HP+LaserJet+1200 HTTP/1.1" 200 0 - -

error.log is empty.  I set the loglevel to debug in the web interfaces,
this leads to:

localhost - - [16/Dec/2006:12:36:29 +0100] "GET /admin?op=config-server HTTP/1.1" 200 0 - -
localhost - - [16/Dec/2006:12:36:29 +0100] "GET /admin?op=config-server HTTP/1.1" 200 8794 - -
localhost - - [16/Dec/2006:12:36:30 +0100] "GET /images/button-use-default-config.gif HTTP/1.1" 200 864 - -
localhost - - [16/Dec/2006:12:36:30 +0100] "GET /images/button-save-changes.gif HTTP/1.1" 200 580 - -
localhost - - [16/Dec/2006:12:36:48 +0100] "POST /admin/ HTTP/1.1" 200 2853 - -
localhost - - [16/Dec/2006:12:36:48 +0100] "PUT /admin/conf/cupsd.conf HTTP/1.1" 401 0 - -
localhost - - [16/Dec/2006:12:36:48 +0100] "POST /admin/ HTTP/1.1" 401 2853 - -
localhost - - [16/Dec/2006:12:36:48 +0100] "POST /admin/ HTTP/1.1" 200 2853 - -
localhost - root [16/Dec/2006:12:36:55 +0100] "POST /admin/ HTTP/1.1" 200 2853 - -
localhost - - [16/Dec/2006:12:36:55 +0100] "PUT /admin/conf/cupsd.conf HTTP/1.1" 401 0 - -
localhost - root [16/Dec/2006:12:36:56 +0100] "PUT /admin/conf/cupsd.conf HTTP/1.1" 201 2252 - -
localhost - root [16/Dec/2006:12:36:55 +0100] "POST /admin/ HTTP/1.1" 200 6302 - -
localhost - root [16/Dec/2006:12:37:01 +0100] "GET /admin/?OP=redirect HTTP/1.1" 200 0 - -
localhost - root [16/Dec/2006:12:37:01 +0100] "GET /admin/?OP=redirect HTTP/1.1" 200 0 - -
localhost - - [16/Dec/2006:12:37:01 +0100] "GET /admin HTTP/1.1" 200 0 - -
localhost - - [16/Dec/2006:12:37:01 +0100] "POST / HTTP/1.1" 200 1615 CUPS-Get-Devices -
localhost - - [16/Dec/2006:12:37:01 +0100] "GET /admin HTTP/1.1" 200 6469 - -
localhost - - [16/Dec/2006:12:37:05 +0100] "GET /admin?op=add-printer&PRINTER_NAME=HP_LaserJet_1200_USB_1&PRINTER_LOCATION=Local+Printer&PRINTER_INFO=HP+LaserJet+1200&DEVICE_URI=usb://HP/LaserJet%25201200|HP+LaserJet+1200 HTTP/1.1" 200 0 - -

and the same problem: Nothing happens, 100% CPU is eaten up.  Now
error.log contains lots of output, I attach the file.

Regards, Frank

-- System Information:
Debian Release: 3.1
  APT prefers unstable
  APT policy: (99, 'unstable')
Architecture: i386 (i686)
Kernel: Linux 2.6.17-2-686
Locale: LANG=de_DE@euro, LC_CTYPE=de_DE@euro (charmap=ISO-8859-15)

Versions of packages cupsys depends on:
ii  adduser               3.63               Add and remove users and groups
ii  cupsys-common         1.2.7-1~bpo.1      Common UNIX Printing System(tm) - 
ii  dbus-1                0.23.4-1           simple interprocess messaging syst
ii  debconf [debconf-2.0] 1.4.30.13          Debian configuration management sy
ii  gs-esp                7.07.1-9           The Ghostscript PostScript interpr
ii  libc6                 2.3.2.ds1-22sarge4 GNU C Library: Shared libraries an
ii  libcupsimage2         1.2.7-1~bpo.1      Common UNIX Printing System(tm) - 
ii  libcupsys2            1.2.7-1~bpo.1      Common UNIX Printing System(tm) - 
ii  libgnutls11           1.0.16-13.2sarge2  GNU TLS library - runtime library
ii  libldap2              2.1.30-8           OpenLDAP libraries
ii  libpam0g              0.76-22            Pluggable Authentication Modules l
ii  libpaper1             1.1.14-3           Library for handling paper charact
ii  libslp1               1.0.11a-2          OpenSLP libraries
ii  lsb-base              3.1-15~bpo.1       Linux Standard Base 3.1 init scrip
ii  patch                 2.5.9-2            Apply a diff file to an original
ii  perl-modules          5.8.4-8sarge5      Core Perl modules
ii  procps                1:3.2.1-2          The /proc file system utilities
ii  xpdf-utils            3.00-13.6          Portable Document Format (PDF) sui
ii  zlib1g                1:1.2.2-4.sarge.2  compression library - runtime

-- debconf information:
  cupsys/raw-print: true
  cupsys/backend: ipp, lpd, parallel, socket, usb

-- 
Dr. Frank Küster
Single Molecule Spectroscopy, Protein Folding @ Inst. f. Biochemie, Univ. Zürich
Debian Developer (teTeX/TeXLive)

I [16/Dec/2006:12:36:56 +0100] Listening to 127.0.0.1:631 (IPv4)
I [16/Dec/2006:12:36:56 +0100] Listening to /var/run/cups/cups.sock (Domain)
I [16/Dec/2006:12:36:56 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [16/Dec/2006:12:36:56 +0100] Using default TempDir of /var/spool/cups/tmp...
I [16/Dec/2006:12:36:56 +0100] Cleaning out old temporary files in "/var/spool/cups/tmp"...
D [16/Dec/2006:12:36:56 +0100] Removed temporary file "/var/spool/cups/tmp/4583da50c9ae4"...
I [16/Dec/2006:12:36:56 +0100] Configured for up to 100 clients.
I [16/Dec/2006:12:36:56 +0100] Allowing up to 100 client connections per host.
I [16/Dec/2006:12:36:56 +0100] Using policy "default" as the default!
I [16/Dec/2006:12:36:56 +0100] Partial reload complete.
I [16/Dec/2006:12:36:56 +0100] Listening to 127.0.0.1:631 on fd 0...
I [16/Dec/2006:12:36:56 +0100] Listening to /var/run/cups/cups.sock on fd 2...
D [16/Dec/2006:12:37:01 +0100] cupsdAcceptClient: 6 from localhost:631 (IPv4)
D [16/Dec/2006:12:37:01 +0100] cupsdReadClient: 6 GET /admin/?OP=redirect HTTP/1.1
D [16/Dec/2006:12:37:01 +0100] cupsdReadClient: 6 Browser asked for language "en-us.utf-8"...
D [16/Dec/2006:12:37:01 +0100] cupsdAuthorize: username="root"
D [16/Dec/2006:12:37:01 +0100] CGI /usr/lib/cups/cgi-bin/admin.cgi started - PID = 9756
I [16/Dec/2006:12:37:01 +0100] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9756)
D [16/Dec/2006:12:37:01 +0100] cupsdSendCommand: 6 file=7
D [16/Dec/2006:12:37:01 +0100] PID 9756 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [16/Dec/2006:12:37:01 +0100] [CGI] admin.cgi started...
D [16/Dec/2006:12:37:01 +0100] [CGI] http=0x8076a90
D [16/Dec/2006:12:37:01 +0100] [CGI] op="redirect"...
D [16/Dec/2006:12:37:01 +0100] cupsdAcceptClient: 9 from localhost (Domain)
D [16/Dec/2006:12:37:01 +0100] cupsdReadClient: 6 GET /admin HTTP/1.1
D [16/Dec/2006:12:37:01 +0100] cupsdReadClient: 6 Browser asked for language "en-us.utf-8"...
D [16/Dec/2006:12:37:01 +0100] cupsdAuthorize: No authentication data provided.
D [16/Dec/2006:12:37:01 +0100] CGI /usr/lib/cups/cgi-bin/admin.cgi started - PID = 9757
I [16/Dec/2006:12:37:01 +0100] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9757)
D [16/Dec/2006:12:37:01 +0100] cupsdSendCommand: 6 file=7
D [16/Dec/2006:12:37:01 +0100] cupsdCloseClient: 9
D [16/Dec/2006:12:37:01 +0100] [CGI] admin.cgi started...
D [16/Dec/2006:12:37:01 +0100] [CGI] http=0x8076a90
D [16/Dec/2006:12:37:01 +0100] [CGI] No form data, showing main menu...
D [16/Dec/2006:12:37:01 +0100] cupsdAcceptClient: 9 from localhost (Domain)
D [16/Dec/2006:12:37:01 +0100] cupsdReadClient: 9 POST / HTTP/1.1
D [16/Dec/2006:12:37:01 +0100] cupsdAuthorize: No authentication data provided.
D [16/Dec/2006:12:37:01 +0100] CUPS-Get-Printers
D [16/Dec/2006:12:37:01 +0100] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [16/Dec/2006:12:37:01 +0100] cupsdReadClient: 9 POST / HTTP/1.1
D [16/Dec/2006:12:37:01 +0100] cupsdAuthorize: No authentication data provided.
D [16/Dec/2006:12:37:01 +0100] CUPS-Get-Devices
D [16/Dec/2006:12:37:01 +0100] CGI /usr/lib/cups/daemon/cups-deviced started - PID = 9758
I [16/Dec/2006:12:37:01 +0100] Started "/usr/lib/cups/daemon/cups-deviced" (pid=9758)
D [16/Dec/2006:12:37:01 +0100] cupsdSendCommand: 9 file=10
D [16/Dec/2006:12:37:01 +0100] [cups-deviced] Added device "smb"...
D [16/Dec/2006:12:37:01 +0100] [cups-deviced] Added device "ipp"...
D [16/Dec/2006:12:37:01 +0100] [cups-deviced] Added device "http"...
D [16/Dec/2006:12:37:01 +0100] [cups-deviced] Added device "lpd"...
D [16/Dec/2006:12:37:02 +0100] [cups-deviced] Added device "parallel:/dev/lp0"...
D [16/Dec/2006:12:37:02 +0100] [cups-deviced] Added device "socket"...
D [16/Dec/2006:12:37:02 +0100] [cups-deviced] Added device "usb://HP/LaserJet%201200"...
D [16/Dec/2006:12:37:02 +0100] [cups-deviced] Added device "cups-pdf:/"...
D [16/Dec/2006:12:37:02 +0100] [cups-deviced] Added device "epson:/dev/lp0"...
D [16/Dec/2006:12:37:02 +0100] PID 9758 (/usr/lib/cups/daemon/cups-deviced) exited with no errors.
D [16/Dec/2006:12:37:02 +0100] [cups-deviced] Added device "canon:/dev/lp0"...
D [16/Dec/2006:12:37:02 +0100] PID 9757 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [16/Dec/2006:12:37:02 +0100] [CGI] /usr/share/cups/drivers/pscript5.dll: No such file or directory
D [16/Dec/2006:12:37:02 +0100] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [16/Dec/2006:12:37:02 +0100] [CGI] locale="en"...
D [16/Dec/2006:12:37:02 +0100] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 0...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{title}" at 205...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting "{refresh_page?" at 374, result=0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Skip first part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 374...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{refresh_page}" at 424...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 427 on character ':'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Output second part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 427...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 428 on character '}'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Finished "{refresh_page?", out=0xa7ed6ee0...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{title}" at 671...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{title}" at 952...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting "{SECTION=admin" at 1411, result=1...
D [16/Dec/2006:12:37:02 +0100] [CGI] Output first part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 1411...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 1412 on character ':'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Skip second part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 1412...
D [16/Dec/2006:12:37:02 +0100] cupsdCloseClient: 9
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 1415 on character '}'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Finished "{SECTION=admin", out=0xa7ed6ee0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting "{SECTION=classes" at 1678, result=0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Skip first part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 1678...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 1679 on character ':'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Output second part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 1679...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 1682 on character '}'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Finished "{SECTION=classes", out=0xa7ed6ee0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting "{SECTION=help" at 1938, result=0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Skip first part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 1938...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 1939 on character ':'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Output second part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 1939...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 1942 on character '}'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Finished "{SECTION=help", out=0xa7ed6ee0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting "{SECTION=jobs" at 2206, result=0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Skip first part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 2206...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 2207 on character ':'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Output second part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 2207...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 2210 on character '}'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Finished "{SECTION=jobs", out=0xa7ed6ee0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting "{SECTION=printers" at 2464, result=0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Skip first part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 2464...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 2465 on character ':'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Output second part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 2465...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 2468 on character '}'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Finished "{SECTION=printers", out=0xa7ed6ee0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 2830 on EOF...
D [16/Dec/2006:12:37:02 +0100] [CGI] cgiCopyTemplateLang(tmpl="admin.tmpl")
D [16/Dec/2006:12:37:02 +0100] [CGI] locale="en"...
D [16/Dec/2006:12:37:02 +0100] [CGI] Template file is "/usr/share/cups/templates/admin.tmpl"...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting "{have_samba?" at 384, result=0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Skip first part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 384...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 512 on character ':'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Output second part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 512...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 513 on character '}'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Finished "{have_samba?", out=0xa7ed6ee0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting "{#device_uri=0" at 535, result=0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Skip first part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 535...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 536 on character ':'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Output second part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 536...
D [16/Dec/2006:12:37:02 +0100] [CGI] Looping on "device_uri" at 586, count=3...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 586...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{device_options}" at 642...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{device_make_and_model}" at 772...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{device_info}" at 787...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 795 on character '}'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 586...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{device_options}" at 642...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{device_make_and_model}" at 772...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{device_info}" at 787...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 795 on character '}'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 586...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{device_options}" at 642...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{device_make_and_model}" at 772...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{device_info}" at 787...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 795 on character '}'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Finished looping on "device_uri"...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 801 on character '}'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Finished "{#device_uri=0", out=0xa7ed6ee0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting "{SETTINGS_ERROR?" at 1850, result=0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Skip first part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 1850...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{SETTINGS_MESSAGE}" at 1871...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{SETTINGS_ERROR}" at 1904...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 1918 on character ':'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Output second part...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 1918...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{?remote_printers}" at 2117...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{?share_printers}" at 2223...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{?remote_admin}" at 2337...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{?user_cancel_any}" at 2435...
D [16/Dec/2006:12:37:02 +0100] [CGI] "{?debug_logging}" at 2552...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 2706 on character '}'...
D [16/Dec/2006:12:37:02 +0100] [CGI] Finished "{SETTINGS_ERROR?", out=0xa7ed6ee0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 2728 on EOF...
D [16/Dec/2006:12:37:02 +0100] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [16/Dec/2006:12:37:02 +0100] [CGI] locale="en"...
D [16/Dec/2006:12:37:02 +0100] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"...
D [16/Dec/2006:12:37:02 +0100] [CGI] Starting at file position 0...
D [16/Dec/2006:12:37:02 +0100] [CGI] Returning at file position 614 on EOF...
D [16/Dec/2006:12:37:05 +0100] cupsdReadClient: 6 GET /admin?op=add-printer&PRINTER_NAME=HP_LaserJet_1200_USB_1&PRINTER_LOCATION=Local+Printer&PRINTER_INFO=HP+LaserJet+1200&DEVICE_URI=usb://HP/LaserJet%25201200|HP+LaserJet+1200 HTTP/1.1
D [16/Dec/2006:12:37:05 +0100] cupsdReadClient: 6 Browser asked for language "en-us.utf-8"...
D [16/Dec/2006:12:37:05 +0100] cupsdAuthorize: No authentication data provided.
D [16/Dec/2006:12:37:05 +0100] CGI /usr/lib/cups/cgi-bin/admin.cgi started - PID = 9769
I [16/Dec/2006:12:37:05 +0100] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9769)
D [16/Dec/2006:12:37:05 +0100] cupsdSendCommand: 6 file=7
D [16/Dec/2006:12:37:05 +0100] [CGI] admin.cgi started...
D [16/Dec/2006:12:37:05 +0100] [CGI] http=0x8076a90
D [16/Dec/2006:12:37:05 +0100] [CGI] op="add-printer"...
D [16/Dec/2006:12:37:05 +0100] [CGI] do_am_printer: DEVICE_URI="usb://HP/LaserJet%201200|HP LaserJet 1200"
D [16/Dec/2006:12:37:05 +0100] cupsdAcceptClient: 9 from localhost (Domain)
D [16/Dec/2006:12:37:05 +0100] cupsdReadClient: 9 POST / HTTP/1.1
D [16/Dec/2006:12:37:05 +0100] cupsdAuthorize: No authentication data provided.
D [16/Dec/2006:12:37:05 +0100] CUPS-Get-PPDs ipp://localhost/printers/
D [16/Dec/2006:12:37:05 +0100] CGI /usr/lib/cups/daemon/cups-driverd started - PID = 9770
I [16/Dec/2006:12:37:05 +0100] Started "/usr/lib/cups/daemon/cups-driverd" (pid=9770)
D [16/Dec/2006:12:37:05 +0100] cupsdSendCommand: 9 file=10
D [16/Dec/2006:12:37:07 +0100] [cups-driverd] Updating ppd "cups-included/Dymo/dymo.ppd"...
D [16/Dec/2006:12:37:07 +0100] [cups-driverd] Updating ppd "cups-included/HP/deskjet2.ppd"...
D [16/Dec/2006:12:37:07 +0100] [cups-driverd] Updating ppd "cups-included/HP/deskjet.ppd"...
D [16/Dec/2006:12:37:07 +0100] [cups-driverd] Updating ppd "cups-included/HP/laserjet.ppd"...
D [16/Dec/2006:12:37:07 +0100] [cups-driverd] Updating ppd "cups-included/Epson/stcolor2.ppd"...
D [16/Dec/2006:12:37:07 +0100] [cups-driverd] Updating ppd "cups-included/Epson/epson24.ppd"...
D [16/Dec/2006:12:37:07 +0100] [cups-driverd] Updating ppd "cups-included/Epson/epson9.ppd"...
D [16/Dec/2006:12:37:07 +0100] [cups-driverd] Updating ppd "cups-included/Epson/stphoto2.ppd"...
D [16/Dec/2006:12:37:07 +0100] [cups-driverd] Updating ppd "cups-included/Epson/stcolor.ppd"...
D [16/Dec/2006:12:37:07 +0100] [cups-driverd] Updating ppd "cups-included/Epson/stphoto.ppd"...
D [16/Dec/2006:12:37:07 +0100] [cups-driverd] Updating ppd "cups-included/Okidata/okidat24.ppd"...
D [16/Dec/2006:12:37:07 +0100] [cups-driverd] Updating ppd "cups-included/Okidata/okidata9.ppd"...
D [16/Dec/2006:12:37:07 +0100] [cups-driverd] Updating ppd "cups-included/Zebra/zebraep1.ppd"...
D [16/Dec/2006:12:37:07 +0100] [cups-driverd] Updating ppd "cups-included/Zebra/zebraep2.ppd"...
D [16/Dec/2006:12:37:07 +0100] [cups-driverd] Updating ppd "cups-included/Zebra/zebra.ppd"...
D [16/Dec/2006:12:37:14 +0100] cupsdAcceptClient: 11 from localhost:631 (IPv4)
D [16/Dec/2006:12:37:14 +0100] cupsdReadClient: 11 GET /admin/log/access_log HTTP/1.1
D [16/Dec/2006:12:37:14 +0100] cupsdReadClient: 11 Browser asked for language "en-us.utf-8"...
D [16/Dec/2006:12:37:14 +0100] cupsdAuthorize: username="root"
D [16/Dec/2006:12:37:14 +0100] write_file: 11 file=12
D [16/Dec/2006:12:38:17 +0100] cupsdCloseClient: 11
D [16/Dec/2006:12:39:00 +0100] cupsdAcceptClient: 11 from localhost:631 (IPv4)
D [16/Dec/2006:12:39:00 +0100] cupsdReadClient: 11 GET /admin/log/error_log HTTP/1.1
D [16/Dec/2006:12:39:00 +0100] cupsdReadClient: 11 Browser asked for language "en-us.utf-8"...
D [16/Dec/2006:12:39:00 +0100] cupsdAuthorize: username="root"
D [16/Dec/2006:12:39:00 +0100] write_file: 11 file=12
I [16/Dec/2006:12:39:40 +0100] Scheduler shutting down normally.
D [16/Dec/2006:12:39:40 +0100] cupsdCloseClient: 6
D [16/Dec/2006:12:39:40 +0100] cupsdCloseClient: 9
D [16/Dec/2006:12:39:40 +0100] cupsdCloseClient: 11
I [16/Dec/2006:12:39:40 +0100] Saving remote.cache...
I [16/Dec/2006:12:39:40 +0100] Saving job cache file "/var/cache/cups/job.cache"...
I [16/Dec/2006:12:39:41 +0100] Listening to 127.0.0.1:631 (IPv4)
I [16/Dec/2006:12:39:41 +0100] Listening to /var/run/cups/cups.sock (Domain)
I [16/Dec/2006:12:39:41 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [16/Dec/2006:12:39:41 +0100] Using default TempDir of /var/spool/cups/tmp...
I [16/Dec/2006:12:39:41 +0100] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [16/Dec/2006:12:39:41 +0100] Configured for up to 100 clients.
I [16/Dec/2006:12:39:41 +0100] Allowing up to 100 client connections per host.
I [16/Dec/2006:12:39:41 +0100] Using policy "default" as the default!
I [16/Dec/2006:12:39:41 +0100] Full reload is required.
I [16/Dec/2006:12:39:41 +0100] Loaded MIME database from '/etc/cups': 34 types, 39 filters...
D [16/Dec/2006:12:39:41 +0100] Loading printer bw_colorlaser...
D [16/Dec/2006:12:39:42 +0100] Loading printer colorlaser...
D [16/Dec/2006:12:39:42 +0100] Loading printer EPSON_Stylus_C82_USB_1...
D [16/Dec/2006:12:39:42 +0100] Loading printer EpsonUSB...
D [16/Dec/2006:12:39:42 +0100] Loading printer lj9000...
D [16/Dec/2006:12:39:42 +0100] Loading printer transparencies...
D [16/Dec/2006:12:39:42 +0100] Loading printer Walter...
I [16/Dec/2006:12:39:42 +0100] Loading job cache file "/var/cache/cups/job.cache"...
D [16/Dec/2006:12:39:42 +0100] Loading job 1 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 2 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 3 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 4 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 5 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 6 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 7 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 8 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 9 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 10 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 11 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 12 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 13 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 14 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 15 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 16 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 17 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 18 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 19 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 20 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 21 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 22 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 23 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 24 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 25 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 26 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 27 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 28 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 29 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 30 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 31 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 32 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 33 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 34 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 35 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 36 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 37 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 38 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 39 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 40 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 41 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 42 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 43 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 44 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 45 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 46 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 47 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 48 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 49 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 50 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 51 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 52 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 53 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 54 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 55 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 56 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 57 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 58 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 59 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 60 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 61 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 62 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 63 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 64 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 65 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 66 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 67 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 68 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 69 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 70 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 71 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 72 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 73 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 74 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 75 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 76 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 77 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 78 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 79 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 80 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 81 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 82 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 83 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 84 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 85 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 86 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 87 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 88 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 89 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 90 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 91 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 92 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 93 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 94 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 95 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 96 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 97 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 98 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 99 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 100 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 101 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 102 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 103 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 104 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 105 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 106 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 107 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 108 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 109 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 110 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 111 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 112 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 113 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 114 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 115 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 116 from cache...
D [16/Dec/2006:12:39:42 +0100] Loading job 117 from cache...
I [16/Dec/2006:12:39:42 +0100] Full reload complete.
I [16/Dec/2006:12:39:42 +0100] Listening to 127.0.0.1:631 on fd 0...
I [16/Dec/2006:12:39:42 +0100] Listening to /var/run/cups/cups.sock on fd 2...

--- End Message ---

--- Begin Message ---

To: Jean Felder <jean.felder@gmail.com>

Cc: 403336-done@bugs.debian.org

Subject: Re: cupsys: Hangs when trying to add a newly found printer

From: Frank Küster <frank@kuesterei.ch>

Date: Thu, 11 Feb 2010 18:04:43 +0100

Message-id: <87sk97ael0.fsf@alhambra.kuesterei.ch>

In-reply-to: <[🔎] 5586c4db1002110701u364918a7ha05c6155561e4551@mail.gmail.com> (Jean Felder's message of "Thu, 11 Feb 2010 16:01:07 +0100")

References: <[🔎] 5586c4db1002110701u364918a7ha05c6155561e4551@mail.gmail.com>
Jean Felder <jean.felder@gmail.com> wrote:

> Hi !
>
> We're trying to clean old bugs !
>
> Can you still reproduce it with 1.4.2-7.1 version ?
>  1.2.x was in fact a long time ago.

No, 

it doesn't seem to happen here - at least not with the single USB
printer that I have available here.  I don't think it makes sense to
keep this bug open.

Regards, Frank



-- 
Dr. Frank Küster
Debian Developer (TeXLive)
VCD Aschaffenburg-Miltenberg, ADFC Miltenberg
B90/Grüne KV Miltenberg
--- End Message ---

Reply to:

Prev by Date: Bug#403336: cupsys: Hangs when trying to add a newly found printer
Next by Date: Processed: Bug#512234: [Debian QA] please review your old bug reports against CUPS
Previous by thread: Bug#403336: cupsys: Hangs when trying to add a newly found printer
Next by thread: Processed: Bug#512234: [Debian QA] please review your old bug reports against CUPS
Index(es):
- Date
- Thread