Your message dated Sun, 1 Jun 2008 23:09:06 +0200 with message-id <20080601210906.GB6509@piware.de> and subject line Cleaning up old bug reports has caused the Debian Bug report #254747, regarding cupsys: Data not being sent - no printing happening 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.) -- 254747: http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=254747 Debian Bug Tracking System Contact owner@bugs.debian.org with problems
--- Begin Message ---
- To: Debian Bug Tracking System <submit@bugs.debian.org>
- Subject: cupsys: Data not being sent - no printing happening
- From: Chris Horn <chris@beefstew.net>
- Date: Wed, 16 Jun 2004 15:16:50 -0400
- Message-id: <E1BaftY-0006a2-00@caverns.beefstew.net>
Package: cupsys Version: 1.1.20final+cvs20040330-3 Severity: normal At first, I thought this bug was the same as #252704, but it doesn't seem that way anymore. That fellow had a socket error, whereas I'm using USB and don't have any permission denied problems. I honestly don't have a clue what's wrong. This printer has worked for about a year, with no issue. Upon a recent upgrade printing from an OS X machine over the network stopped working - the printer would begin receiving the data and after some seconds just seem to "crash." This promted me to start messing with the PPDs and other settings. That's when it stopped printing all-together. I backed up /etc/cups and purged all CUPS related packags. I've now reinstalled, added a printer the normal way (not touched any other settings) but printing just doesn't happen! I've attached an error_log for you to take a look at. Any help would be much appreciated! Chris Horn -- System Information: Debian Release: testing/unstable APT prefers testing APT policy: (500, 'testing') Architecture: i386 (i686) Kernel: Linux 2.6.6 Locale: LANG=C, LC_CTYPE=C Versions of packages cupsys depends on: ii adduser 3.56 Add and remove users and groups ii debconf 1.4.25 Debian configuration management sy ii gs-esp 7.07.1-6 The Ghostscript PostScript interpr ii libc6 2.3.2.ds1-12 GNU C Library: Shared libraries an ii libcupsimage2 1.1.20final+cvs20040330-3 Common UNIX Printing System(tm) - ii libcupsys2 1.1.20final+cvs20040330-3 Common UNIX Printing System(tm) - ii libgnutls7 0.8.12-5 GNU TLS library - runtime library ii libpam0g 0.76-21 Pluggable Authentication Modules l ii libpaper1 1.1.14 Library for handling paper charact ii libslp1 1.0.11-7 OpenSLP libraries ii zlib1g 1:1.2.1.1-3 compression library - runtime -- debconf information: cupsys/raw-print: true cupsys/backend: ipp, lpd, parallel, socket, usbI [16/Jun/2004:14:18:48 -0400] Listening to 0:631 D [16/Jun/2004:14:18:48 -0400] AddLocation: added location '/' D [16/Jun/2004:14:18:48 -0400] DenyIP: / deny 00000000/00000000 D [16/Jun/2004:14:18:48 -0400] AllowIP: / allow 7f000001/ffffffff D [16/Jun/2004:14:18:48 -0400] AddLocation: added location '/admin' D [16/Jun/2004:14:18:48 -0400] DenyIP: /admin deny 00000000/00000000 D [16/Jun/2004:14:18:48 -0400] AllowIP: /admin allow 7f000001/ffffffff I [16/Jun/2004:14:18:48 -0400] Loaded configuration file "/etc/cups/cupsd.conf" I [16/Jun/2004:14:18:48 -0400] Configured for up to 100 clients. I [16/Jun/2004:14:18:48 -0400] Allowing up to 100 client connections per host. I [16/Jun/2004:14:18:48 -0400] Full reload is required. D [16/Jun/2004:14:18:48 -0400] LoadAllPrinters: Loading printer HL-1450... d [16/Jun/2004:14:18:48 -0400] AddPrinter("HL-1450") d [16/Jun/2004:14:18:48 -0400] Adding filter application/vnd.cups-raw printer/HL-1450 0 - d [16/Jun/2004:14:18:48 -0400] FindBest: uri = "/printers/HL-1450"... d [16/Jun/2004:14:18:48 -0400] FindBest: Location / Limit 7f d [16/Jun/2004:14:18:48 -0400] FindBest: Location /admin Limit 7f d [16/Jun/2004:14:18:48 -0400] FindBest: best = "/" d [16/Jun/2004:14:18:48 -0400] Adding filter application/vnd.cups-postscript printer/HL-1450 0 foomatic-rip D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "ipp"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "lpd"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "cups-pdf:/"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "socket"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "usb://Brother/HL-1450%20series"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "usb:/dev/usb/lp1"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "usb:/dev/usb/lp2"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "usb:/dev/usb/lp3"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "usb:/dev/usb/lp4"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "usb:/dev/usb/lp5"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "usb:/dev/usb/lp6"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "usb:/dev/usb/lp7"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "usb:/dev/usb/lp8"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "usb:/dev/usb/lp9"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "usb:/dev/usb/lp10"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "usb:/dev/usb/lp11"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "usb:/dev/usb/lp12"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "usb:/dev/usb/lp13"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "usb:/dev/usb/lp14"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "usb:/dev/usb/lp15"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "http"... D [16/Jun/2004:14:18:48 -0400] LoadDevices: Added device "smb"... I [16/Jun/2004:14:18:55 -0400] LoadPPDs: Read "/etc/cups/ppds.dat", 242 PPDs... I [16/Jun/2004:14:18:55 -0400] LoadPPDs: No new or changed PPDs... D [16/Jun/2004:14:18:55 -0400] LoadAllJobs: Scanning /var/spool/cups... D [16/Jun/2004:14:18:55 -0400] LoadAllJobs: Loading attributes for job 1... D [16/Jun/2004:14:18:55 -0400] LoadAllJobs: Loading attributes for job 2... D [16/Jun/2004:14:18:55 -0400] LoadAllJobs: Loading attributes for job 3... I [16/Jun/2004:14:18:55 -0400] Full reload complete. D [16/Jun/2004:14:18:55 -0400] StartListening: NumListeners=1 D [16/Jun/2004:14:18:55 -0400] StartListening: address=00000000 port=631 D [16/Jun/2004:14:18:55 -0400] ResumeListening: setting input bits... d [16/Jun/2004:14:18:55 -0400] ResumeListening: Adding fd 0 to InputSet... d [16/Jun/2004:14:18:55 -0400] StartBrowsing: Adding fd 2 to InputSet... d [16/Jun/2004:14:18:55 -0400] StartServer: Adding fd 4 to InputSet... d [16/Jun/2004:14:18:56 -0400] DeleteCert: removing certificate for pid 0 d [16/Jun/2004:14:18:56 -0400] AddCert: adding certificate for pid 0 d [16/Jun/2004:14:18:56 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:19:04 -0400] AcceptClient(lis=0x808bc90) 0 NumClients = 0 D [16/Jun/2004:14:19:04 -0400] AcceptClient: 6 from localhost:631. d [16/Jun/2004:14:19:04 -0400] AcceptClient: Adding fd 6 to InputSet... d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=-1 D [16/Jun/2004:14:19:04 -0400] ReadClient: 6 POST / HTTP/1.1 d [16/Jun/2004:14:19:04 -0400] decode_auth(0x40371008): Authorization string = "" d [16/Jun/2004:14:19:04 -0400] decode_auth: 6 username="" d [16/Jun/2004:14:19:04 -0400] IsAuthorized: con->uri = "/" d [16/Jun/2004:14:19:04 -0400] FindBest: uri = "/"... d [16/Jun/2004:14:19:04 -0400] FindBest: Location / Limit 7f d [16/Jun/2004:14:19:04 -0400] FindBest: Location /admin Limit 7f d [16/Jun/2004:14:19:04 -0400] FindBest: best = "/" d [16/Jun/2004:14:19:04 -0400] IsAuthorized: auth = 0, satisfy=0... d [16/Jun/2004:14:19:04 -0400] POST / d [16/Jun/2004:14:19:04 -0400] CONTENT_TYPE = application/ipp d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 137, con->file = -1 d [16/Jun/2004:14:19:04 -0400] ProcessIPPRequest(0x40371008[6]): operation_id = 4002 d [16/Jun/2004:14:19:04 -0400] get_printers(0x40371008[6], 0) d [16/Jun/2004:14:19:04 -0400] add_printer_state_reasons(0x40371008[6], 0x808cc88[HL-1450]) d [16/Jun/2004:14:19:04 -0400] add_queued_job_count(0x40371008[6], 0x808cc88[HL-1450]) d [16/Jun/2004:14:19:04 -0400] copy_attrs(0x80be718, 0x808d238, 0x80be638, 0) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be718, 0x808d370[printer-name,4,42]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be718, 0x808d558[job-sheets-default,4,42]) d [16/Jun/2004:14:19:04 -0400] copy_attrs(0x80be718, 0x80929e8, 0x80be638, 0) D [16/Jun/2004:14:19:04 -0400] ProcessIPPRequest: 6 status_code=1 d [16/Jun/2004:14:19:04 -0400] ProcessIPPRequest: Adding fd 6 to OutputSet... d [16/Jun/2004:14:19:04 -0400] WriteClient: Removing fd 6 from OutputSet... d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:14:19:04 -0400] ReadClient: httpGets returned EOF... D [16/Jun/2004:14:19:04 -0400] CloseClient: 6 d [16/Jun/2004:14:19:04 -0400] CloseClient: Removing fd 6 from InputSet and OutputSet... d [16/Jun/2004:14:19:04 -0400] AcceptClient(lis=0x808bc90) 0 NumClients = 0 D [16/Jun/2004:14:19:04 -0400] AcceptClient: 6 from localhost:631. d [16/Jun/2004:14:19:04 -0400] AcceptClient: Adding fd 6 to InputSet... d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=-1 D [16/Jun/2004:14:19:04 -0400] ReadClient: 6 POST / HTTP/1.1 d [16/Jun/2004:14:19:04 -0400] decode_auth(0x40371008): Authorization string = "" d [16/Jun/2004:14:19:04 -0400] decode_auth: 6 username="" d [16/Jun/2004:14:19:04 -0400] IsAuthorized: con->uri = "/" d [16/Jun/2004:14:19:04 -0400] FindBest: uri = "/"... d [16/Jun/2004:14:19:04 -0400] FindBest: Location / Limit 7f d [16/Jun/2004:14:19:04 -0400] FindBest: Location /admin Limit 7f d [16/Jun/2004:14:19:04 -0400] FindBest: best = "/" d [16/Jun/2004:14:19:04 -0400] IsAuthorized: auth = 0, satisfy=0... d [16/Jun/2004:14:19:04 -0400] POST / d [16/Jun/2004:14:19:04 -0400] CONTENT_TYPE = application/ipp d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 137, con->file = -1 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 129, con->file = -1 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 95, con->file = -1 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 61, con->file = -1 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=23, file=-1 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 24, con->file = -1 d [16/Jun/2004:14:19:04 -0400] ProcessIPPRequest(0x40371008[6]): operation_id = 4005 d [16/Jun/2004:14:19:04 -0400] get_printers(0x40371008[6], 1) D [16/Jun/2004:14:19:04 -0400] ProcessIPPRequest: 6 status_code=1 d [16/Jun/2004:14:19:04 -0400] ProcessIPPRequest: Adding fd 6 to OutputSet... d [16/Jun/2004:14:19:04 -0400] select_timeout: 23 seconds to send browse update d [16/Jun/2004:14:19:04 -0400] WriteClient: Removing fd 6 from OutputSet... d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:14:19:04 -0400] ReadClient: httpGets returned EOF... D [16/Jun/2004:14:19:04 -0400] CloseClient: 6 d [16/Jun/2004:14:19:04 -0400] CloseClient: Removing fd 6 from InputSet and OutputSet... d [16/Jun/2004:14:19:04 -0400] AcceptClient(lis=0x808bc90) 0 NumClients = 0 D [16/Jun/2004:14:19:04 -0400] AcceptClient: 6 from localhost:631. d [16/Jun/2004:14:19:04 -0400] AcceptClient: Adding fd 6 to InputSet... d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=-1 D [16/Jun/2004:14:19:04 -0400] ReadClient: 6 POST / HTTP/1.1 d [16/Jun/2004:14:19:04 -0400] decode_auth(0x40371008): Authorization string = "" d [16/Jun/2004:14:19:04 -0400] decode_auth: 6 username="" d [16/Jun/2004:14:19:04 -0400] IsAuthorized: con->uri = "/" d [16/Jun/2004:14:19:04 -0400] FindBest: uri = "/"... d [16/Jun/2004:14:19:04 -0400] FindBest: Location / Limit 7f d [16/Jun/2004:14:19:04 -0400] FindBest: Location /admin Limit 7f d [16/Jun/2004:14:19:04 -0400] FindBest: best = "/" d [16/Jun/2004:14:19:04 -0400] IsAuthorized: auth = 0, satisfy=0... d [16/Jun/2004:14:19:04 -0400] POST / d [16/Jun/2004:14:19:04 -0400] CONTENT_TYPE = application/ipp d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 77, con->file = -1 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 69, con->file = -1 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 35, con->file = -1 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 1, con->file = -1 d [16/Jun/2004:14:19:04 -0400] ProcessIPPRequest(0x40371008[6]): operation_id = 4001 d [16/Jun/2004:14:19:04 -0400] get_default(0x40371008[6]) d [16/Jun/2004:14:19:04 -0400] copy_attrs(0x80be9a0, 0x808d238, (nil), 0) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808d260[printer-uri-supported,4,45]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808d2b8[uri-authentication-supported,4,44]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808d328[uri-security-supported,4,44]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808d370[printer-name,4,42]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808d398[printer-location,4,41]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808d400[printer-info,4,41]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808d440[printer-more-info,4,45]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808d4b0[job-quota-period,4,21]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808d4f0[job-k-limit,4,21]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808d518[job-page-limit,4,21]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808d558[job-sheets-default,4,42]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808d5a0[device-uri,4,45]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x80d1a88[color-supported,4,22]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x80d1998[pages-per-minute,4,21]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x80d0718[printer-make-and-model,4,41]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808d5f0[media-supported,4,44]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x80d2880[media-default,4,44]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x8091cc0[finishings-supported,4,23]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x80d3048[finishings-default,4,23]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x80d3288[printer-type,4,23]) d [16/Jun/2004:14:19:04 -0400] copy_attrs(0x80be9a0, 0x80929e8, (nil), 0) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x8090c50[pdl-override-supported,4,44]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x80907f0[ipp-versions-supported,4,44]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x80d2e10[operations-supported,4,23]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x8090ae0[multiple-document-jobs-supported,4,22]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x8090ab8[multiple-operation-time-out,4,21]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808cc18[multiple-document-handling-supported,4,44]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x8090970[charset-configured,4,47]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808c9f8[charset-supported,4,47]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x8090930[natural-language-configured,4,48]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x80908f0[generated-natural-language-supported,4,48]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x80908a8[document-format-default,4,49]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x8090658[document-format-supported,4,80000049]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808c998[compression-supported,4,44]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x8090860[job-priority-supported,4,21]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x80905f0[job-priority-default,4,21]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x8090588[copies-supported,4,33]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x8090530[copies-default,4,21]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x8090170[page-ranges-supported,4,22]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x8093148[number-up-supported,4,21]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x808cc48[number-up-default,4,21]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x8090a70[orientation-requested-supported,4,23]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x80931c8[orientation-requested-default,4,23]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x8090b30[job-hold-until-supported,4,44]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x8092e98[job-hold-until-default,4,44]) d [16/Jun/2004:14:19:04 -0400] copy_attribute(0x80be9a0, 0x8090ca8[job-sheets-supported,4,42]) D [16/Jun/2004:14:19:04 -0400] ProcessIPPRequest: 6 status_code=0 d [16/Jun/2004:14:19:04 -0400] ProcessIPPRequest: Adding fd 6 to OutputSet... d [16/Jun/2004:14:19:04 -0400] WriteClient: Removing fd 6 from OutputSet... d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=-1 D [16/Jun/2004:14:19:04 -0400] ReadClient: 6 POST /printers/HL-1450 HTTP/1.1 d [16/Jun/2004:14:19:04 -0400] decode_auth(0x40371008): Authorization string = "" d [16/Jun/2004:14:19:04 -0400] decode_auth: 6 username="" d [16/Jun/2004:14:19:04 -0400] IsAuthorized: con->uri = "/printers/HL-1450" d [16/Jun/2004:14:19:04 -0400] FindBest: uri = "/printers/HL-1450"... d [16/Jun/2004:14:19:04 -0400] FindBest: Location / Limit 7f d [16/Jun/2004:14:19:04 -0400] FindBest: Location /admin Limit 7f d [16/Jun/2004:14:19:04 -0400] FindBest: best = "/" d [16/Jun/2004:14:19:04 -0400] IsAuthorized: auth = 0, satisfy=0... d [16/Jun/2004:14:19:04 -0400] POST /printers/HL-1450 d [16/Jun/2004:14:19:04 -0400] CONTENT_TYPE = application/ipp d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 229652, con->file = -1 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 REQUEST /var/spool/cups/00000003=8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 writing 1562 bytes to 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 227654, con->file = 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 writing 32768 bytes to 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 194886, con->file = 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 writing 32768 bytes to 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 162118, con->file = 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 writing 32768 bytes to 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 129350, con->file = 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 writing 32768 bytes to 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 96582, con->file = 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 writing 14333 bytes to 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 82249, con->file = 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 writing 32768 bytes to 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 49481, con->file = 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 writing 16873 bytes to 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 32608, con->file = 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 writing 32608 bytes to 8 d [16/Jun/2004:14:19:04 -0400] ReadClient: 6 Closing data file 8, size = 229216. d [16/Jun/2004:14:19:04 -0400] ProcessIPPRequest(0x40371008[6]): operation_id = 0002 d [16/Jun/2004:14:19:04 -0400] print_job(0x40371008[6], ipp://localhost:631/printers/HL-1450) D [16/Jun/2004:14:19:04 -0400] print_job: auto-typing file... D [16/Jun/2004:14:19:04 -0400] print_job: request file type is application/postscript. d [16/Jun/2004:14:19:04 -0400] check_quotas(0x40371008[6], 0x808cc88[HL-1450]) D [16/Jun/2004:14:19:04 -0400] check_quotas: requesting-user-name = 'reds' D [16/Jun/2004:14:19:04 -0400] print_job: requesting-user-name = 'reds' I [16/Jun/2004:14:19:04 -0400] Adding start banner page "none" to job 4. d [16/Jun/2004:14:19:04 -0400] copy_banner(0x40371008[6], 0x809c0d0[4], none) d [16/Jun/2004:14:19:04 -0400] add_file(con=0x40371008[6], job=4, filetype=application/postscript, compression=0) I [16/Jun/2004:14:19:04 -0400] Adding end banner page "none" to job 4. d [16/Jun/2004:14:19:04 -0400] copy_banner(0x40371008[6], 0x809c0d0[4], none) I [16/Jun/2004:14:19:04 -0400] Job 4 queued on 'HL-1450' by 'reds'. D [16/Jun/2004:14:19:04 -0400] Job 4 hold_until = 0 d [16/Jun/2004:14:19:04 -0400] SaveJob: Closing file 8... D [16/Jun/2004:14:19:04 -0400] StartJob(4, 0x808cc88) D [16/Jun/2004:14:19:04 -0400] StartJob() id = 4, file = 0/1 D [16/Jun/2004:14:19:04 -0400] job-sheets=none,none D [16/Jun/2004:14:19:04 -0400] banner_page = 0 D [16/Jun/2004:14:19:04 -0400] StartJob: argv = "HL-1450","4","reds","(stdin)","1","outputorder=normal number-up=1 nowrap position=top-left InputSlot=auto MediaType=Plain PageSize=Letter Resolution=1200x600dpi EconoMode=off","/var/spool/cups/d00004-001" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[1]="SOFTWARE=CUPS/1.1" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[2]="USER=root" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[3]="CHARSET=iso-8859-1" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[4]="LANG=en" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[5]="TZ=US/Eastern" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[6]="PPD=/etc/cups/ppd/HL-1450.ppd" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[7]="CUPS_SERVERROOT=/etc/cups" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[8]="RIP_MAX_CACHE=8m" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[9]="TMPDIR=/var/spool/cups/tmp" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[10]="CONTENT_TYPE=application/postscript" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[11]="DEVICE_URI=usb://Brother/HL-1450%20series" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[12]="PRINTER=HL-1450" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[13]="CUPS_DATADIR=/usr/share/cups" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[14]="CUPS_FONTPATH=/usr/share/cups/fonts" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[15]="CUPS_SERVER=localhost" D [16/Jun/2004:14:19:04 -0400] StartJob: envp[16]="IPP_PORT=631" d [16/Jun/2004:14:19:04 -0400] StartJob: Allocating status buffer... D [16/Jun/2004:14:19:04 -0400] StartJob: statusfds = [ 8 9 ] D [16/Jun/2004:14:19:04 -0400] StartJob: filterfds[1] = [ 10 -1 ] D [16/Jun/2004:14:19:04 -0400] StartJob: filter = "/usr/lib/cups/filter/pstops" D [16/Jun/2004:14:19:04 -0400] StartJob: filterfds[0] = [ 11 12 ] D [16/Jun/2004:14:19:04 -0400] start_process("/usr/lib/cups/filter/pstops", 0xbffefe10, 0xbffef180, 10, 12, 9) I [16/Jun/2004:14:19:04 -0400] Started filter /usr/lib/cups/filter/pstops (PID 30077) for job 4. D [16/Jun/2004:14:19:04 -0400] StartJob: filter = "/usr/lib/cups/filter/foomatic-rip" D [16/Jun/2004:14:19:04 -0400] StartJob: filterfds[1] = [ 10 13 ] D [16/Jun/2004:14:19:04 -0400] start_process("/usr/lib/cups/filter/foomatic-rip", 0xbffefe10, 0xbffef180, 11, 13, 9) I [16/Jun/2004:14:19:04 -0400] Started filter /usr/lib/cups/filter/foomatic-rip (PID 30078) for job 4. D [16/Jun/2004:14:19:04 -0400] StartJob: backend = "/usr/lib/cups/backend/usb" D [16/Jun/2004:14:19:04 -0400] StartJob: filterfds[0] = [ -1 11 ] D [16/Jun/2004:14:19:04 -0400] start_process("/usr/lib/cups/backend/usb", 0xbffefe10, 0xbffef180, 10, 11, 9) I [16/Jun/2004:14:19:04 -0400] Started backend /usr/lib/cups/backend/usb (PID 30079) for job 4. d [16/Jun/2004:14:19:04 -0400] StartJob: Adding fd 8 to InputSet... d [16/Jun/2004:14:19:04 -0400] add_job_state_reasons(0x40371008[6], 4) D [16/Jun/2004:14:19:04 -0400] ProcessIPPRequest: 6 status_code=0 d [16/Jun/2004:14:19:04 -0400] ProcessIPPRequest: Adding fd 6 to OutputSet... E [16/Jun/2004:14:19:04 -0400] PID 30078 stopped with status 22! D [16/Jun/2004:14:19:04 -0400] [Job 4] Page = 612x792; 0,0 to 612,792 D [16/Jun/2004:14:19:04 -0400] [Job 4] slowcollate=0, slowduplex=0, sloworder=0 D [16/Jun/2004:14:19:04 -0400] [Job 4] 0 %%BoundingBox: 0 0 612 792 D [16/Jun/2004:14:19:04 -0400] [Job 4] 0 %%Creator: Mozilla PostScript module (rv:1.7/2004051407) D [16/Jun/2004:14:19:04 -0400] [Job 4] 0 %%DocumentData: Clean8Bit D [16/Jun/2004:14:19:04 -0400] [Job 4] 0 %%DocumentPaperSizes: Letter D [16/Jun/2004:14:19:04 -0400] [Job 4] 0 %%Orientation: Portrait D [16/Jun/2004:14:19:04 -0400] [Job 4] 0 %%Pages: (atend) D [16/Jun/2004:14:19:04 -0400] [Job 4] 0 %%PageOrder: Ascend D [16/Jun/2004:14:19:04 -0400] [Job 4] 0 %%EndComments D [16/Jun/2004:14:19:04 -0400] [Job 4] 0 %%BeginProlog D [16/Jun/2004:14:19:04 -0400] [Job 4] /usr/lib/cups/filter/foomatic-rip: No such file or directory d [16/Jun/2004:14:19:04 -0400] WriteClient: Removing fd 6 from OutputSet... D [16/Jun/2004:14:19:04 -0400] [Job 4] 0 %%EndProlog D [16/Jun/2004:14:19:04 -0400] [Job 4] 0 %%Page: 1 1 D [16/Jun/2004:14:19:04 -0400] [Job 4] 0 %%Page: 1 1 D [16/Jun/2004:14:19:04 -0400] [Job 4] pw = 612.0, pl = 792.0 D [16/Jun/2004:14:19:04 -0400] [Job 4] PageLeft = 0.0, PageRight = 612.0 D [16/Jun/2004:14:19:04 -0400] [Job 4] PageTop = 792.0, PageBottom = 0.0 D [16/Jun/2004:14:19:04 -0400] [Job 4] PageWidth = 612.0, PageLength = 792.0 D [16/Jun/2004:14:19:04 -0400] [Job 4] 0 %%BeginPageSetup D [16/Jun/2004:14:19:04 -0400] [Job 4] 0 %%EndPageSetup D [16/Jun/2004:14:19:04 -0400] [Job 4] 0 %%Trailer D [16/Jun/2004:14:19:04 -0400] [Job 4] Saw Trailer! D [16/Jun/2004:14:19:04 -0400] [Job 4] Saw EOF! d [16/Jun/2004:14:19:04 -0400] PID 30077 exited with no errors. D [16/Jun/2004:14:19:04 -0400] [Job 4] Printer using device file "/dev/usb/lp0"... D [16/Jun/2004:14:19:04 -0400] [Job 4] LPGETSTATUS returned a port status of 18... I [16/Jun/2004:14:19:04 -0400] [Job 4] Ready to print. d [16/Jun/2004:14:19:04 -0400] PID 30079 exited with no errors. D [16/Jun/2004:14:19:04 -0400] UpdateJob: job 4, file 0 is complete. d [16/Jun/2004:14:19:04 -0400] UpdateJob: Removing fd 8 from InputSet... D [16/Jun/2004:14:19:04 -0400] CancelJob: id = 4 D [16/Jun/2004:14:19:04 -0400] StopJob: id = 4, force = 0 D [16/Jun/2004:14:19:04 -0400] StopJob: printer state is 3 d [16/Jun/2004:14:19:04 -0400] StopJob: Freeing status buffer... d [16/Jun/2004:14:19:04 -0400] SaveJob: Closing file 8... d [16/Jun/2004:14:19:04 -0400] SaveJob: Closing file 8... d [16/Jun/2004:14:19:04 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:14:19:04 -0400] ReadClient: httpGets returned EOF... D [16/Jun/2004:14:19:04 -0400] CloseClient: 6 d [16/Jun/2004:14:19:04 -0400] CloseClient: Removing fd 6 from InputSet and OutputSet... d [16/Jun/2004:14:19:05 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:19:36 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:20:07 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:20:38 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:21:09 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:21:40 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:22:11 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:22:42 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:23:13 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:23:44 -0400] select_timeout: 13 seconds to update root certificate d [16/Jun/2004:14:23:57 -0400] DeleteCert: removing certificate for pid 0 d [16/Jun/2004:14:23:57 -0400] AddCert: adding certificate for pid 0 d [16/Jun/2004:14:23:57 -0400] select_timeout: 18 seconds to send browse update d [16/Jun/2004:14:24:15 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:24:46 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:25:17 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:25:48 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:26:19 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:26:50 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:27:21 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:27:52 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:28:23 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:28:54 -0400] select_timeout: 4 seconds to update root certificate d [16/Jun/2004:14:28:58 -0400] DeleteCert: removing certificate for pid 0 d [16/Jun/2004:14:28:58 -0400] AddCert: adding certificate for pid 0 d [16/Jun/2004:14:28:58 -0400] select_timeout: 27 seconds to send browse update d [16/Jun/2004:14:29:25 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:29:56 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:30:27 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:30:58 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:31:29 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:32:00 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:32:32 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:33:03 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:33:34 -0400] select_timeout: 25 seconds to update root certificate d [16/Jun/2004:14:33:59 -0400] DeleteCert: removing certificate for pid 0 d [16/Jun/2004:14:33:59 -0400] AddCert: adding certificate for pid 0 d [16/Jun/2004:14:33:59 -0400] select_timeout: 6 seconds to send browse update d [16/Jun/2004:14:34:05 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:34:36 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:35:07 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:35:39 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:36:10 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:36:41 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:37:12 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:37:43 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:38:14 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:38:45 -0400] select_timeout: 15 seconds to update root certificate d [16/Jun/2004:14:39:00 -0400] DeleteCert: removing certificate for pid 0 d [16/Jun/2004:14:39:00 -0400] AddCert: adding certificate for pid 0 d [16/Jun/2004:14:39:00 -0400] select_timeout: 16 seconds to send browse update d [16/Jun/2004:14:39:16 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:39:47 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:40:18 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:40:49 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:41:20 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:41:51 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:42:22 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:42:53 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:43:24 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:43:55 -0400] select_timeout: 6 seconds to update root certificate d [16/Jun/2004:14:44:01 -0400] DeleteCert: removing certificate for pid 0 d [16/Jun/2004:14:44:01 -0400] AddCert: adding certificate for pid 0 d [16/Jun/2004:14:44:01 -0400] select_timeout: 25 seconds to send browse update d [16/Jun/2004:14:44:26 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:44:57 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:45:28 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:45:59 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:46:30 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:47:01 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:47:32 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:48:03 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:48:34 -0400] select_timeout: 28 seconds to update root certificate d [16/Jun/2004:14:49:02 -0400] DeleteCert: removing certificate for pid 0 d [16/Jun/2004:14:49:02 -0400] AddCert: adding certificate for pid 0 d [16/Jun/2004:14:49:02 -0400] select_timeout: 3 seconds to send browse update d [16/Jun/2004:14:49:05 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:49:36 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:50:07 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:50:38 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:51:09 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:51:40 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:52:11 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:52:42 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:53:13 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:53:44 -0400] select_timeout: 19 seconds to update root certificate d [16/Jun/2004:14:54:03 -0400] DeleteCert: removing certificate for pid 0 d [16/Jun/2004:14:54:03 -0400] AddCert: adding certificate for pid 0 d [16/Jun/2004:14:54:03 -0400] select_timeout: 12 seconds to send browse update d [16/Jun/2004:14:54:15 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:54:46 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:55:17 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:55:48 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:56:19 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:56:50 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:57:21 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:57:52 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:58:23 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:58:54 -0400] select_timeout: 10 seconds to update root certificate d [16/Jun/2004:14:59:04 -0400] DeleteCert: removing certificate for pid 0 d [16/Jun/2004:14:59:04 -0400] AddCert: adding certificate for pid 0 d [16/Jun/2004:14:59:04 -0400] select_timeout: 21 seconds to send browse update d [16/Jun/2004:14:59:25 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:14:59:56 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:00:27 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:00:58 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:01:29 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:02:00 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:02:31 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:03:02 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:03:33 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:04:04 -0400] DeleteCert: removing certificate for pid 0 d [16/Jun/2004:15:04:04 -0400] AddCert: adding certificate for pid 0 d [16/Jun/2004:15:04:04 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:04:35 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:05:06 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:05:37 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:05:50 -0400] AcceptClient(lis=0x808bc90) 0 NumClients = 0 D [16/Jun/2004:15:05:50 -0400] AcceptClient: 6 from localhost:631. d [16/Jun/2004:15:05:50 -0400] AcceptClient: Adding fd 6 to InputSet... d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=-1 D [16/Jun/2004:15:05:50 -0400] ReadClient: 6 POST / HTTP/1.1 d [16/Jun/2004:15:05:50 -0400] decode_auth(0x40371008): Authorization string = "" d [16/Jun/2004:15:05:50 -0400] decode_auth: 6 username="" d [16/Jun/2004:15:05:50 -0400] IsAuthorized: con->uri = "/" d [16/Jun/2004:15:05:50 -0400] FindBest: uri = "/"... d [16/Jun/2004:15:05:50 -0400] FindBest: Location / Limit 7f d [16/Jun/2004:15:05:50 -0400] FindBest: Location /admin Limit 7f d [16/Jun/2004:15:05:50 -0400] FindBest: best = "/" d [16/Jun/2004:15:05:50 -0400] IsAuthorized: auth = 0, satisfy=0... d [16/Jun/2004:15:05:50 -0400] POST / d [16/Jun/2004:15:05:50 -0400] CONTENT_TYPE = application/ipp d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 137, con->file = -1 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 129, con->file = -1 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 95, con->file = -1 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 61, con->file = -1 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=23, file=-1 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 24, con->file = -1 d [16/Jun/2004:15:05:50 -0400] ProcessIPPRequest(0x40371008[6]): operation_id = 4002 d [16/Jun/2004:15:05:50 -0400] get_printers(0x40371008[6], 0) d [16/Jun/2004:15:05:50 -0400] add_printer_state_reasons(0x40371008[6], 0x808cc88[HL-1450]) d [16/Jun/2004:15:05:50 -0400] add_queued_job_count(0x40371008[6], 0x808cc88[HL-1450]) d [16/Jun/2004:15:05:50 -0400] copy_attrs(0x80bea78, 0x808d238, 0x80be9c8, 0) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bea78, 0x808d370[printer-name,4,42]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bea78, 0x808d558[job-sheets-default,4,42]) d [16/Jun/2004:15:05:50 -0400] copy_attrs(0x80bea78, 0x80929e8, 0x80be9c8, 0) D [16/Jun/2004:15:05:50 -0400] ProcessIPPRequest: 6 status_code=1 d [16/Jun/2004:15:05:50 -0400] ProcessIPPRequest: Adding fd 6 to OutputSet... d [16/Jun/2004:15:05:50 -0400] select_timeout: 18 seconds to send browse update d [16/Jun/2004:15:05:50 -0400] WriteClient: Removing fd 6 from OutputSet... d [16/Jun/2004:15:05:50 -0400] AcceptClient(lis=0x808bc90) 0 NumClients = 1 D [16/Jun/2004:15:05:50 -0400] AcceptClient: 8 from localhost:631. d [16/Jun/2004:15:05:50 -0400] AcceptClient: Adding fd 8 to InputSet... d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:15:05:50 -0400] ReadClient: httpGets returned EOF... D [16/Jun/2004:15:05:50 -0400] CloseClient: 6 d [16/Jun/2004:15:05:50 -0400] CloseClient: Removing fd 6 from InputSet and OutputSet... d [16/Jun/2004:15:05:50 -0400] ReadClient: 8, used=0, file=-1 D [16/Jun/2004:15:05:50 -0400] ReadClient: 8 POST / HTTP/1.1 d [16/Jun/2004:15:05:50 -0400] decode_auth(0x40371008): Authorization string = "" d [16/Jun/2004:15:05:50 -0400] decode_auth: 8 username="" d [16/Jun/2004:15:05:50 -0400] IsAuthorized: con->uri = "/" d [16/Jun/2004:15:05:50 -0400] FindBest: uri = "/"... d [16/Jun/2004:15:05:50 -0400] FindBest: Location / Limit 7f d [16/Jun/2004:15:05:50 -0400] FindBest: Location /admin Limit 7f d [16/Jun/2004:15:05:50 -0400] FindBest: best = "/" d [16/Jun/2004:15:05:50 -0400] IsAuthorized: auth = 0, satisfy=0... d [16/Jun/2004:15:05:50 -0400] POST / d [16/Jun/2004:15:05:50 -0400] CONTENT_TYPE = application/ipp d [16/Jun/2004:15:05:50 -0400] ReadClient: 8 con->data_encoding = length, con->data_remaining = 137, con->file = -1 d [16/Jun/2004:15:05:50 -0400] ProcessIPPRequest(0x40371008[8]): operation_id = 4005 d [16/Jun/2004:15:05:50 -0400] get_printers(0x40371008[8], 1) D [16/Jun/2004:15:05:50 -0400] ProcessIPPRequest: 8 status_code=1 d [16/Jun/2004:15:05:50 -0400] ProcessIPPRequest: Adding fd 8 to OutputSet... d [16/Jun/2004:15:05:50 -0400] WriteClient: Removing fd 8 from OutputSet... d [16/Jun/2004:15:05:50 -0400] ReadClient: 8, used=0, file=-1 d [16/Jun/2004:15:05:50 -0400] ReadClient: httpGets returned EOF... D [16/Jun/2004:15:05:50 -0400] CloseClient: 8 d [16/Jun/2004:15:05:50 -0400] CloseClient: Removing fd 8 from InputSet and OutputSet... d [16/Jun/2004:15:05:50 -0400] AcceptClient(lis=0x808bc90) 0 NumClients = 0 D [16/Jun/2004:15:05:50 -0400] AcceptClient: 6 from localhost:631. d [16/Jun/2004:15:05:50 -0400] AcceptClient: Adding fd 6 to InputSet... d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=-1 D [16/Jun/2004:15:05:50 -0400] ReadClient: 6 POST / HTTP/1.1 d [16/Jun/2004:15:05:50 -0400] decode_auth(0x40371008): Authorization string = "" d [16/Jun/2004:15:05:50 -0400] decode_auth: 6 username="" d [16/Jun/2004:15:05:50 -0400] IsAuthorized: con->uri = "/" d [16/Jun/2004:15:05:50 -0400] FindBest: uri = "/"... d [16/Jun/2004:15:05:50 -0400] FindBest: Location / Limit 7f d [16/Jun/2004:15:05:50 -0400] FindBest: Location /admin Limit 7f d [16/Jun/2004:15:05:50 -0400] FindBest: best = "/" d [16/Jun/2004:15:05:50 -0400] IsAuthorized: auth = 0, satisfy=0... d [16/Jun/2004:15:05:50 -0400] POST / d [16/Jun/2004:15:05:50 -0400] CONTENT_TYPE = application/ipp d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 77, con->file = -1 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 69, con->file = -1 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 35, con->file = -1 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 1, con->file = -1 d [16/Jun/2004:15:05:50 -0400] ProcessIPPRequest(0x40371008[6]): operation_id = 4001 d [16/Jun/2004:15:05:50 -0400] get_default(0x40371008[6]) d [16/Jun/2004:15:05:50 -0400] copy_attrs(0x80bef08, 0x808d238, (nil), 0) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808d260[printer-uri-supported,4,45]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808d2b8[uri-authentication-supported,4,44]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808d328[uri-security-supported,4,44]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808d370[printer-name,4,42]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808d398[printer-location,4,41]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808d400[printer-info,4,41]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808d440[printer-more-info,4,45]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808d4b0[job-quota-period,4,21]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808d4f0[job-k-limit,4,21]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808d518[job-page-limit,4,21]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808d558[job-sheets-default,4,42]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808d5a0[device-uri,4,45]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x80d1a88[color-supported,4,22]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x80d1998[pages-per-minute,4,21]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x80d0718[printer-make-and-model,4,41]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808d5f0[media-supported,4,44]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x80d2880[media-default,4,44]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x8091cc0[finishings-supported,4,23]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x80d3048[finishings-default,4,23]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x80d3288[printer-type,4,23]) d [16/Jun/2004:15:05:50 -0400] copy_attrs(0x80bef08, 0x80929e8, (nil), 0) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x8090c50[pdl-override-supported,4,44]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x80907f0[ipp-versions-supported,4,44]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x80d2e10[operations-supported,4,23]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x8090ae0[multiple-document-jobs-supported,4,22]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x8090ab8[multiple-operation-time-out,4,21]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808cc18[multiple-document-handling-supported,4,44]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x8090970[charset-configured,4,47]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808c9f8[charset-supported,4,47]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x8090930[natural-language-configured,4,48]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x80908f0[generated-natural-language-supported,4,48]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x80908a8[document-format-default,4,49]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x8090658[document-format-supported,4,80000049]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808c998[compression-supported,4,44]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x8090860[job-priority-supported,4,21]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x80905f0[job-priority-default,4,21]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x8090588[copies-supported,4,33]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x8090530[copies-default,4,21]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x8090170[page-ranges-supported,4,22]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x8093148[number-up-supported,4,21]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x808cc48[number-up-default,4,21]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x8090a70[orientation-requested-supported,4,23]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x80931c8[orientation-requested-default,4,23]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x8090b30[job-hold-until-supported,4,44]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x8092e98[job-hold-until-default,4,44]) d [16/Jun/2004:15:05:50 -0400] copy_attribute(0x80bef08, 0x8090ca8[job-sheets-supported,4,42]) D [16/Jun/2004:15:05:50 -0400] ProcessIPPRequest: 6 status_code=0 d [16/Jun/2004:15:05:50 -0400] ProcessIPPRequest: Adding fd 6 to OutputSet... d [16/Jun/2004:15:05:50 -0400] WriteClient: Removing fd 6 from OutputSet... d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=-1 D [16/Jun/2004:15:05:50 -0400] ReadClient: 6 POST /printers/HL-1450 HTTP/1.1 d [16/Jun/2004:15:05:50 -0400] decode_auth(0x40371008): Authorization string = "" d [16/Jun/2004:15:05:50 -0400] decode_auth: 6 username="" d [16/Jun/2004:15:05:50 -0400] IsAuthorized: con->uri = "/printers/HL-1450" d [16/Jun/2004:15:05:50 -0400] FindBest: uri = "/printers/HL-1450"... d [16/Jun/2004:15:05:50 -0400] FindBest: Location / Limit 7f d [16/Jun/2004:15:05:50 -0400] FindBest: Location /admin Limit 7f d [16/Jun/2004:15:05:50 -0400] FindBest: best = "/" d [16/Jun/2004:15:05:50 -0400] IsAuthorized: auth = 0, satisfy=0... d [16/Jun/2004:15:05:50 -0400] POST /printers/HL-1450 d [16/Jun/2004:15:05:50 -0400] CONTENT_TYPE = application/ipp d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 229652, con->file = -1 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 REQUEST /var/spool/cups/00000004=8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 writing 1562 bytes to 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 227654, con->file = 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 writing 32768 bytes to 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 194886, con->file = 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 writing 32768 bytes to 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 162118, con->file = 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 writing 32768 bytes to 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 129350, con->file = 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 writing 32768 bytes to 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 96582, con->file = 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 writing 32768 bytes to 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 63814, con->file = 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 writing 14333 bytes to 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 49481, con->file = 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 writing 32768 bytes to 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 con->data_encoding = length, con->data_remaining = 16713, con->file = 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 writing 16713 bytes to 8 d [16/Jun/2004:15:05:50 -0400] ReadClient: 6 Closing data file 8, size = 229216. d [16/Jun/2004:15:05:50 -0400] ProcessIPPRequest(0x40371008[6]): operation_id = 0002 d [16/Jun/2004:15:05:50 -0400] print_job(0x40371008[6], ipp://localhost:631/printers/HL-1450) D [16/Jun/2004:15:05:50 -0400] print_job: auto-typing file... D [16/Jun/2004:15:05:50 -0400] print_job: request file type is application/postscript. d [16/Jun/2004:15:05:50 -0400] check_quotas(0x40371008[6], 0x808cc88[HL-1450]) D [16/Jun/2004:15:05:50 -0400] check_quotas: requesting-user-name = 'reds' D [16/Jun/2004:15:05:50 -0400] print_job: requesting-user-name = 'reds' I [16/Jun/2004:15:05:50 -0400] Adding start banner page "none" to job 5. d [16/Jun/2004:15:05:50 -0400] copy_banner(0x40371008[6], 0x809d4c8[5], none) d [16/Jun/2004:15:05:50 -0400] add_file(con=0x40371008[6], job=5, filetype=application/postscript, compression=0) I [16/Jun/2004:15:05:50 -0400] Adding end banner page "none" to job 5. d [16/Jun/2004:15:05:50 -0400] copy_banner(0x40371008[6], 0x809d4c8[5], none) I [16/Jun/2004:15:05:50 -0400] Job 5 queued on 'HL-1450' by 'reds'. D [16/Jun/2004:15:05:50 -0400] Job 5 hold_until = 0 d [16/Jun/2004:15:05:50 -0400] SaveJob: Closing file 8... D [16/Jun/2004:15:05:50 -0400] StartJob(5, 0x808cc88) D [16/Jun/2004:15:05:50 -0400] StartJob() id = 5, file = 0/1 D [16/Jun/2004:15:05:50 -0400] job-sheets=none,none D [16/Jun/2004:15:05:50 -0400] banner_page = 0 D [16/Jun/2004:15:05:50 -0400] StartJob: argv = "HL-1450","5","reds","(stdin)","1","outputorder=normal number-up=1 nowrap position=top-left InputSlot=auto MediaType=Plain PageSize=Letter Resolution=1200x600dpi EconoMode=off","/var/spool/cups/d00005-001" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[0]="PATH=/usr/lib/cups/filter:/bin:/usr/bin" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[1]="SOFTWARE=CUPS/1.1" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[2]="USER=root" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[3]="CHARSET=iso-8859-1" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[4]="LANG=en" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[5]="TZ=US/Eastern" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[6]="PPD=/etc/cups/ppd/HL-1450.ppd" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[7]="CUPS_SERVERROOT=/etc/cups" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[8]="RIP_MAX_CACHE=8m" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[9]="TMPDIR=/var/spool/cups/tmp" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[10]="CONTENT_TYPE=application/postscript" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[11]="DEVICE_URI=usb://Brother/HL-1450%20series" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[12]="PRINTER=HL-1450" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[13]="CUPS_DATADIR=/usr/share/cups" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[14]="CUPS_FONTPATH=/usr/share/cups/fonts" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[15]="CUPS_SERVER=localhost" D [16/Jun/2004:15:05:50 -0400] StartJob: envp[16]="IPP_PORT=631" d [16/Jun/2004:15:05:50 -0400] StartJob: Allocating status buffer... D [16/Jun/2004:15:05:50 -0400] StartJob: statusfds = [ 8 9 ] D [16/Jun/2004:15:05:50 -0400] StartJob: filterfds[1] = [ 10 -1 ] D [16/Jun/2004:15:05:50 -0400] StartJob: filter = "/usr/lib/cups/filter/pstops" D [16/Jun/2004:15:05:50 -0400] StartJob: filterfds[0] = [ 11 12 ] D [16/Jun/2004:15:05:50 -0400] start_process("/usr/lib/cups/filter/pstops", 0xbffefe10, 0xbffef180, 10, 12, 9) I [16/Jun/2004:15:05:50 -0400] Started filter /usr/lib/cups/filter/pstops (PID 6027) for job 5. D [16/Jun/2004:15:05:50 -0400] StartJob: filter = "/usr/lib/cups/filter/foomatic-rip" D [16/Jun/2004:15:05:50 -0400] StartJob: filterfds[1] = [ 10 13 ] D [16/Jun/2004:15:05:50 -0400] start_process("/usr/lib/cups/filter/foomatic-rip", 0xbffefe10, 0xbffef180, 11, 13, 9) I [16/Jun/2004:15:05:50 -0400] Started filter /usr/lib/cups/filter/foomatic-rip (PID 6028) for job 5. D [16/Jun/2004:15:05:50 -0400] StartJob: backend = "/usr/lib/cups/backend/usb" D [16/Jun/2004:15:05:50 -0400] StartJob: filterfds[0] = [ -1 11 ] D [16/Jun/2004:15:05:50 -0400] start_process("/usr/lib/cups/backend/usb", 0xbffefe10, 0xbffef180, 10, 11, 9) I [16/Jun/2004:15:05:50 -0400] Started backend /usr/lib/cups/backend/usb (PID 6029) for job 5. d [16/Jun/2004:15:05:50 -0400] StartJob: Adding fd 8 to InputSet... d [16/Jun/2004:15:05:50 -0400] add_job_state_reasons(0x40371008[6], 5) D [16/Jun/2004:15:05:50 -0400] ProcessIPPRequest: 6 status_code=0 d [16/Jun/2004:15:05:50 -0400] ProcessIPPRequest: Adding fd 6 to OutputSet... d [16/Jun/2004:15:05:50 -0400] WriteClient: Removing fd 6 from OutputSet... d [16/Jun/2004:15:05:50 -0400] ReadClient: 6, used=0, file=-1 d [16/Jun/2004:15:05:50 -0400] ReadClient: httpGets returned EOF... D [16/Jun/2004:15:05:50 -0400] CloseClient: 6 d [16/Jun/2004:15:05:50 -0400] CloseClient: Removing fd 6 from InputSet and OutputSet... D [16/Jun/2004:15:05:50 -0400] [Job 5] /usr/lib/cups/filter/foomatic-rip: No such file or directory E [16/Jun/2004:15:05:50 -0400] PID 6028 stopped with status 22! D [16/Jun/2004:15:05:50 -0400] [Job 5] Printer using device file "/dev/usb/lp0"... D [16/Jun/2004:15:05:50 -0400] [Job 5] LPGETSTATUS returned a port status of 18... I [16/Jun/2004:15:05:50 -0400] [Job 5] Ready to print. d [16/Jun/2004:15:05:50 -0400] PID 6029 exited with no errors. D [16/Jun/2004:15:05:50 -0400] [Job 5] Page = 612x792; 0,0 to 612,792 D [16/Jun/2004:15:05:50 -0400] [Job 5] slowcollate=0, slowduplex=0, sloworder=0 D [16/Jun/2004:15:05:50 -0400] [Job 5] 0 %%BoundingBox: 0 0 612 792 D [16/Jun/2004:15:05:50 -0400] [Job 5] 0 %%Creator: Mozilla PostScript module (rv:1.7/2004051407) D [16/Jun/2004:15:05:50 -0400] [Job 5] 0 %%DocumentData: Clean8Bit D [16/Jun/2004:15:05:50 -0400] [Job 5] 0 %%DocumentPaperSizes: Letter D [16/Jun/2004:15:05:50 -0400] [Job 5] 0 %%Orientation: Portrait D [16/Jun/2004:15:05:50 -0400] [Job 5] 0 %%Pages: (atend) D [16/Jun/2004:15:05:50 -0400] [Job 5] 0 %%PageOrder: Ascend D [16/Jun/2004:15:05:50 -0400] [Job 5] 0 %%EndComments D [16/Jun/2004:15:05:50 -0400] [Job 5] 0 %%BeginProlog D [16/Jun/2004:15:05:50 -0400] [Job 5] 0 %%EndProlog D [16/Jun/2004:15:05:50 -0400] [Job 5] 0 %%Page: 1 1 D [16/Jun/2004:15:05:50 -0400] [Job 5] 0 %%Page: 1 1 D [16/Jun/2004:15:05:50 -0400] [Job 5] pw = 612.0, pl = 792.0 D [16/Jun/2004:15:05:50 -0400] [Job 5] PageLeft = 0.0, PageRight = 612.0 D [16/Jun/2004:15:05:50 -0400] [Job 5] PageTop = 792.0, PageBottom = 0.0 D [16/Jun/2004:15:05:50 -0400] [Job 5] PageWidth = 612.0, PageLength = 792.0 D [16/Jun/2004:15:05:50 -0400] [Job 5] 0 %%BeginPageSetup D [16/Jun/2004:15:05:50 -0400] [Job 5] 0 %%EndPageSetup D [16/Jun/2004:15:05:50 -0400] [Job 5] 0 %%Trailer D [16/Jun/2004:15:05:50 -0400] [Job 5] Saw Trailer! D [16/Jun/2004:15:05:50 -0400] [Job 5] Saw EOF! d [16/Jun/2004:15:05:50 -0400] PID 6027 exited with no errors. D [16/Jun/2004:15:05:50 -0400] UpdateJob: job 5, file 0 is complete. d [16/Jun/2004:15:05:50 -0400] UpdateJob: Removing fd 8 from InputSet... D [16/Jun/2004:15:05:50 -0400] CancelJob: id = 5 D [16/Jun/2004:15:05:50 -0400] StopJob: id = 5, force = 0 D [16/Jun/2004:15:05:50 -0400] StopJob: printer state is 3 d [16/Jun/2004:15:05:50 -0400] StopJob: Freeing status buffer... d [16/Jun/2004:15:05:50 -0400] SaveJob: Closing file 6... d [16/Jun/2004:15:05:50 -0400] SaveJob: Closing file 6... d [16/Jun/2004:15:05:51 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:06:22 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:06:53 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:07:24 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:07:55 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:08:26 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:08:57 -0400] select_timeout: 8 seconds to update root certificate d [16/Jun/2004:15:09:05 -0400] DeleteCert: removing certificate for pid 0 d [16/Jun/2004:15:09:05 -0400] AddCert: adding certificate for pid 0 d [16/Jun/2004:15:09:05 -0400] select_timeout: 23 seconds to send browse update d [16/Jun/2004:15:09:28 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:09:59 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:10:30 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:11:01 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:11:32 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:12:03 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:12:34 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:13:05 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:13:36 -0400] select_timeout: 30 seconds to update root certificate d [16/Jun/2004:15:14:06 -0400] DeleteCert: removing certificate for pid 0 d [16/Jun/2004:15:14:06 -0400] AddCert: adding certificate for pid 0 d [16/Jun/2004:15:14:06 -0400] select_timeout: 1 seconds to send browse update d [16/Jun/2004:15:14:07 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:14:38 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:15:09 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:15:40 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:16:11 -0400] select_timeout: 31 seconds to send browse update d [16/Jun/2004:15:16:42 -0400] select_timeout: 31 seconds to send browse update
--- End Message ---
--- Begin Message ---
- To: 190135-done@bugs.debian.org, 201553-done@bugs.debian.org, 220642-done@bugs.debian.org, 280236-done@bugs.debian.org, 283581-done@bugs.debian.org, 284575-done@bugs.debian.org, 288784-done@bugs.debian.org, 353763-done@bugs.debian.org, 354579-done@bugs.debian.org, 354581-done@bugs.debian.org, 354590-done@bugs.debian.org, 369563-done@bugs.debian.org, 384448-done@bugs.debian.org, 414299-done@bugs.debian.org, 283484-done@bugs.debian.org, 189705-done@bugs.debian.org, 423943-done@bugs.debian.org, 116286-done@bugs.debian.org, 126170-done@bugs.debian.org, 130661-done@bugs.debian.org, 140673-done@bugs.debian.org, 162459-done@bugs.debian.org, 163051-done@bugs.debian.org, 166729-done@bugs.debian.org, 179349-done@bugs.debian.org, 188237-done@bugs.debian.org, 192905-done@bugs.debian.org, 193671-done@bugs.debian.org, 197200-done@bugs.debian.org, 208059-done@bugs.debian.org, 223465-done@bugs.debian.org, 235522-done@bugs.debian.org, 238757-done@bugs.debian.org, 245514-done@bugs.debian.org, 254747-done@bugs.debian.org, 257051-done@bugs.debian.org, 265817-done@bugs.debian.org, 271157-done@bugs.debian.org, 271874-done@bugs.debian.org, 289893-done@bugs.debian.org, 290346-done@bugs.debian.org, 293247-done@bugs.debian.org, 313416-done@bugs.debian.org, 319093-done@bugs.debian.org, 323795-done@bugs.debian.org, 323796-done@bugs.debian.org, 335391-done@bugs.debian.org, 342735-done@bugs.debian.org, 343509-done@bugs.debian.org, 343518-done@bugs.debian.org, 343535-done@bugs.debian.org, 351893-done@bugs.debian.org, 366674-done@bugs.debian.org, 370607-done@bugs.debian.org, 371740-done@bugs.debian.org, 372309-done@bugs.debian.org, 373281-done@bugs.debian.org, 373793-done@bugs.debian.org, 375254-done@bugs.debian.org, 375907-done@bugs.debian.org, 377079-done@bugs.debian.org, 381250-done@bugs.debian.org, 382499-done@bugs.debian.org, 382936-done@bugs.debian.org, 389240-done@bugs.debian.org, 400262-done@bugs.debian.org, 220611-done@bugs.debian.org, 291788-done@bugs.debian.org, 356468-done@bugs.debian.org, 251391-done@bugs.debian.org, 259774-done@bugs.debian.org, 301012-done@bugs.debian.org, 304136-done@bugs.debian.org, 344542-done@bugs.debian.org, 368019-done@bugs.debian.org, 370559-done@bugs.debian.org, 377604-done@bugs.debian.org, 380549-done@bugs.debian.org, 380640-done@bugs.debian.org, 381428-done@bugs.debian.org, 383290-done@bugs.debian.org, 386785-done@bugs.debian.org, 387201-done@bugs.debian.org, 390522-done@bugs.debian.org
- Subject: Cleaning up old bug reports
- From: Martin Pitt <mpitt@debian.org>
- Date: Sun, 1 Jun 2008 23:09:06 +0200
- Message-id: <20080601210906.GB6509@piware.de>
Dear bug submitter, This bug was reported against a very old version of cups (older than in the current stable Debian version "Etch"). About a year ago, Martin-Eric Racine asked for feedback whether the bug still applied to the current Debian Etch version, but we did not get any feedback. In order to not keep around old, hardware-specific, and inactive bugs around forever, I close this bug now. Please report back if you still experience the problem with the current version in Etch; if you can test Lenny/unstable, that would be highly appreciated, of course. I will reopen this bug then. Thank you for your report! Martin -- Martin Pitt | http://www.piware.de Ubuntu Developer (www.ubuntu.com) | Debian Developer (www.debian.org)Attachment: signature.asc
Description: Digital signature
--- End Message ---